{"level":"warn","ts":"2026-06-13T22:43:35.78371Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T22:43:35.783803Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-13T22:43:35.783999Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T22:43:35.784017Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-06-13T22:43:35.784084Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-13T22:43:35.785052Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-06-13T22:43:35.78515Z","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":8,"max-cpu-available":8,"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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-13T22:43:35.791951Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.130391ms"} {"level":"info","ts":"2026-06-13T22:43:35.802552Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-06-13T22:43:35.802638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-06-13T22:43:35.802663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-06-13T22:43:35.802678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-13T22:43:35.802688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-06-13T22:43:35.802735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-06-13T22:43:35.814625Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-13T22:43:35.817805Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-13T22:43:35.821515Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-13T22:43:35.83066Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-13T22:43:35.830962Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-13T22:43:35.831128Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-13T22:43:35.831201Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-13T22:43:35.831211Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-13T22:43:35.831834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-06-13T22:43:35.831944Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-06-13T22:43:35.835857Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-13T22:43:35.835905Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-06-13T22:43:35.835957Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-06-13T22:43:35.8361Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-13T22:43:35.836137Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-13T22:43:36.203825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-06-13T22:43:36.203883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-13T22:43:36.203914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-06-13T22:43:36.20393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-06-13T22:43:36.20394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-06-13T22:43:36.203952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-06-13T22:43:36.203964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-06-13T22:43:36.239824Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-06-13T22:43:36.239813Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T22:43:36.239916Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T22:43:36.240459Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:43:36.240484Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-13T22:43:36.240552Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-13T22:43:36.244142Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-06-13T22:43:36.244757Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-13T22:43:36.340736Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:43:36.341116Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T22:43:36.341215Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-06-13T22:43:42.410164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.685967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:43:42.410243Z","caller":"traceutil/trace.go:171","msg":"trace[1748092344] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:265; }","duration":"198.827338ms","start":"2026-06-13T22:43:42.211404Z","end":"2026-06-13T22:43:42.410232Z","steps":["trace[1748092344] 'range keys from in-memory index tree' (duration: 198.598946ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:42.564908Z","caller":"traceutil/trace.go:171","msg":"trace[2025138784] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"159.24157ms","start":"2026-06-13T22:43:42.405644Z","end":"2026-06-13T22:43:42.564886Z","steps":["trace[2025138784] 'process raft request' (duration: 159.156239ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:42.886673Z","caller":"traceutil/trace.go:171","msg":"trace[637851556] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:273; }","duration":"473.996046ms","start":"2026-06-13T22:43:42.412659Z","end":"2026-06-13T22:43:42.886655Z","steps":["trace[637851556] 'read index received' (duration: 152.184614ms)","trace[637851556] 'applied index is now lower than readState.Index' (duration: 321.810892ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:43:42.886656Z","caller":"traceutil/trace.go:171","msg":"trace[1946015694] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"480.784298ms","start":"2026-06-13T22:43:42.405834Z","end":"2026-06-13T22:43:42.886618Z","steps":["trace[1946015694] 'process raft request' (duration: 480.623787ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:42.886781Z","caller":"traceutil/trace.go:171","msg":"trace[1594679317] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"480.132553ms","start":"2026-06-13T22:43:42.406645Z","end":"2026-06-13T22:43:42.886778Z","steps":["trace[1594679317] 'process raft request' (duration: 479.924121ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:43:42.886766Z","caller":"traceutil/trace.go:171","msg":"trace[6875927] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"480.779689ms","start":"2026-06-13T22:43:42.40598Z","end":"2026-06-13T22:43:42.88676Z","steps":["trace[6875927] 'process raft request' (duration: 480.566357ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:42.886981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.306908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-13T22:43:42.887016Z","caller":"traceutil/trace.go:171","msg":"trace[1525257574] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:266; }","duration":"474.354319ms","start":"2026-06-13T22:43:42.41265Z","end":"2026-06-13T22:43:42.887004Z","steps":["trace[1525257574] 'agreement among raft nodes before linearized reading' (duration: 474.189407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:42.887031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:42.406633Z","time spent":"480.164813ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:43:42.887052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:42.412638Z","time spent":"474.398679ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-06-13T22:43:42.887234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.059954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4659"} {"level":"info","ts":"2026-06-13T22:43:42.887252Z","caller":"traceutil/trace.go:171","msg":"trace[1657115493] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:266; }","duration":"321.108885ms","start":"2026-06-13T22:43:42.566137Z","end":"2026-06-13T22:43:42.887246Z","steps":["trace[1657115493] 'agreement among raft nodes before linearized reading' (duration: 321.071275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:42.887268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:42.566123Z","time spent":"321.142235ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4682,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-06-13T22:43:42.887334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:42.405807Z","time spent":"480.989291ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T22:43:42.887445Z","caller":"traceutil/trace.go:171","msg":"trace[1862379594] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"459.106527ms","start":"2026-06-13T22:43:42.428297Z","end":"2026-06-13T22:43:42.887403Z","steps":["trace[1862379594] 'process raft request' (duration: 458.28734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:43:42.887538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:42.405972Z","time spent":"480.851069ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T22:43:42.887842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:43:42.428275Z","time spent":"459.261569ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:44:18.345364Z","caller":"traceutil/trace.go:171","msg":"trace[782261569] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"107.390238ms","start":"2026-06-13T22:44:18.237939Z","end":"2026-06-13T22:44:18.345329Z","steps":["trace[782261569] 'process raft request' (duration: 106.757976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:44:18.68956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.752149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:44:18.689642Z","caller":"traceutil/trace.go:171","msg":"trace[1730190439] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:590; }","duration":"265.876025ms","start":"2026-06-13T22:44:18.423749Z","end":"2026-06-13T22:44:18.689625Z","steps":["trace[1730190439] 'range keys from in-memory index tree' (duration: 265.641493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:44:18.689962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.191091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fvjgm\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-13T22:44:18.690163Z","caller":"traceutil/trace.go:171","msg":"trace[1634663406] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fvjgm; range_end:; response_count:1; response_revision:590; }","duration":"200.461295ms","start":"2026-06-13T22:44:18.489682Z","end":"2026-06-13T22:44:18.690143Z","steps":["trace[1634663406] 'range keys from in-memory index tree' (duration: 199.890856ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:44:18.86937Z","caller":"traceutil/trace.go:171","msg":"trace[993568153] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"159.470087ms","start":"2026-06-13T22:44:18.709857Z","end":"2026-06-13T22:44:18.869327Z","steps":["trace[993568153] 'process raft request' (duration: 157.784551ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:45:08.916095Z","caller":"traceutil/trace.go:171","msg":"trace[2145397155] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"162.231858ms","start":"2026-06-13T22:45:08.753845Z","end":"2026-06-13T22:45:08.916077Z","steps":["trace[2145397155] 'process raft request' (duration: 162.078284ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:45:31.090292Z","caller":"traceutil/trace.go:171","msg":"trace[1614901137] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"101.310348ms","start":"2026-06-13T22:45:30.98896Z","end":"2026-06-13T22:45:31.09027Z","steps":["trace[1614901137] 'process raft request' (duration: 101.182945ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:45:54.386356Z","caller":"traceutil/trace.go:171","msg":"trace[682248927] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"367.461874ms","start":"2026-06-13T22:45:54.018872Z","end":"2026-06-13T22:45:54.386334Z","steps":["trace[682248927] 'process raft request' (duration: 307.606701ms)","trace[682248927] 'compare' (duration: 59.771511ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:45:54.386467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T22:45:54.018853Z","time spent":"367.559206ms","remote":"127.0.0.1:48334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T22:45:54.40476Z","caller":"traceutil/trace.go:171","msg":"trace[1579786349] linearizableReadLoop","detail":"{readStateIndex:1900; appliedIndex:1898; }","duration":"281.765913ms","start":"2026-06-13T22:45:54.122982Z","end":"2026-06-13T22:45:54.404748Z","steps":["trace[1579786349] 'read index received' (duration: 203.644118ms)","trace[1579786349] 'applied index is now lower than readState.Index' (duration: 78.121165ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:45:54.405024Z","caller":"traceutil/trace.go:171","msg":"trace[1266881215] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"282.942558ms","start":"2026-06-13T22:45:54.122068Z","end":"2026-06-13T22:45:54.405011Z","steps":["trace[1266881215] 'process raft request' (duration: 282.636091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:54.405151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.146541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-13T22:45:54.405243Z","caller":"traceutil/trace.go:171","msg":"trace[1688488690] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1827; }","duration":"282.296574ms","start":"2026-06-13T22:45:54.122931Z","end":"2026-06-13T22:45:54.405227Z","steps":["trace[1688488690] 'agreement among raft nodes before linearized reading' (duration: 282.017808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:54.405246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.045183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e8cfc8a-8231-4edf-95c0-1b7659fd9d78\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-06-13T22:45:54.405051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.360669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-06-13T22:45:54.40534Z","caller":"traceutil/trace.go:171","msg":"trace[1837352047] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5e8cfc8a-8231-4edf-95c0-1b7659fd9d78; range_end:; response_count:1; response_revision:1827; }","duration":"100.168056ms","start":"2026-06-13T22:45:54.30513Z","end":"2026-06-13T22:45:54.405298Z","steps":["trace[1837352047] 'agreement among raft nodes before linearized reading' (duration: 99.861619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:54.405267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.219384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-13T22:45:54.405362Z","caller":"traceutil/trace.go:171","msg":"trace[990816770] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1827; }","duration":"243.708157ms","start":"2026-06-13T22:45:54.161635Z","end":"2026-06-13T22:45:54.405344Z","steps":["trace[990816770] 'agreement among raft nodes before linearized reading' (duration: 243.253757ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:45:54.405451Z","caller":"traceutil/trace.go:171","msg":"trace[260089875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1827; }","duration":"190.450549ms","start":"2026-06-13T22:45:54.214989Z","end":"2026-06-13T22:45:54.405439Z","steps":["trace[260089875] 'agreement among raft nodes before linearized reading' (duration: 190.148262ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:45:54.732973Z","caller":"traceutil/trace.go:171","msg":"trace[1126686049] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"160.713871ms","start":"2026-06-13T22:45:54.572237Z","end":"2026-06-13T22:45:54.732951Z","steps":["trace[1126686049] 'read index received' (duration: 141.51727ms)","trace[1126686049] 'applied index is now lower than readState.Index' (duration: 19.192671ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:45:54.733249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.004438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-13T22:45:54.733308Z","caller":"traceutil/trace.go:171","msg":"trace[766442250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1829; }","duration":"161.109599ms","start":"2026-06-13T22:45:54.572182Z","end":"2026-06-13T22:45:54.733292Z","steps":["trace[766442250] 'agreement among raft nodes before linearized reading' (duration: 160.865004ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:45:54.733267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.561874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-13T22:45:54.733422Z","caller":"traceutil/trace.go:171","msg":"trace[933672482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1829; }","duration":"150.771839ms","start":"2026-06-13T22:45:54.582642Z","end":"2026-06-13T22:45:54.733414Z","steps":["trace[933672482] 'agreement among raft nodes before linearized reading' (duration: 150.422262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:00.558017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.268806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-13T22:46:00.558052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.947938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-13T22:46:00.558121Z","caller":"traceutil/trace.go:171","msg":"trace[144965762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1890; }","duration":"117.044859ms","start":"2026-06-13T22:46:00.441059Z","end":"2026-06-13T22:46:00.558104Z","steps":["trace[144965762] 'range keys from in-memory index tree' (duration: 116.786284ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:00.558076Z","caller":"traceutil/trace.go:171","msg":"trace[1862483836] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1890; }","duration":"110.353868ms","start":"2026-06-13T22:46:00.447707Z","end":"2026-06-13T22:46:00.558061Z","steps":["trace[1862483836] 'range keys from in-memory index tree' (duration: 110.129823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:00.558241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.985079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-13T22:46:00.558322Z","caller":"traceutil/trace.go:171","msg":"trace[106423916] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1890; }","duration":"114.117252ms","start":"2026-06-13T22:46:00.444187Z","end":"2026-06-13T22:46:00.558304Z","steps":["trace[106423916] 'range keys from in-memory index tree' (duration: 113.822836ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:06.781912Z","caller":"traceutil/trace.go:171","msg":"trace[146620848] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2003; }","duration":"187.889532ms","start":"2026-06-13T22:46:06.593991Z","end":"2026-06-13T22:46:06.781881Z","steps":["trace[146620848] 'read index received' (duration: 187.706578ms)","trace[146620848] 'applied index is now lower than readState.Index' (duration: 181.604µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:46:06.781967Z","caller":"traceutil/trace.go:171","msg":"trace[1926684983] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"190.801427ms","start":"2026-06-13T22:46:06.591128Z","end":"2026-06-13T22:46:06.78193Z","steps":["trace[1926684983] 'process raft request' (duration: 190.578152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:06.782159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.156297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-13T22:46:06.782232Z","caller":"traceutil/trace.go:171","msg":"trace[535305187] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1927; }","duration":"188.243218ms","start":"2026-06-13T22:46:06.593967Z","end":"2026-06-13T22:46:06.78221Z","steps":["trace[535305187] 'agreement among raft nodes before linearized reading' (duration: 188.067665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:06.782233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.949495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-13T22:46:06.782285Z","caller":"traceutil/trace.go:171","msg":"trace[971345756] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1927; }","duration":"186.045757ms","start":"2026-06-13T22:46:06.596225Z","end":"2026-06-13T22:46:06.782271Z","steps":["trace[971345756] 'agreement among raft nodes before linearized reading' (duration: 185.864524ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:22.628446Z","caller":"traceutil/trace.go:171","msg":"trace[1464860063] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"138.686052ms","start":"2026-06-13T22:46:22.489739Z","end":"2026-06-13T22:46:22.628425Z","steps":["trace[1464860063] 'process raft request' (duration: 138.53878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:28.966095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.097236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T22:46:28.966158Z","caller":"traceutil/trace.go:171","msg":"trace[1153127302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2062; }","duration":"221.198278ms","start":"2026-06-13T22:46:28.744944Z","end":"2026-06-13T22:46:28.966142Z","steps":["trace[1153127302] 'range keys from in-memory index tree' (duration: 221.005994ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:29.461674Z","caller":"traceutil/trace.go:171","msg":"trace[1545827786] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"185.362238ms","start":"2026-06-13T22:46:29.276282Z","end":"2026-06-13T22:46:29.461644Z","steps":["trace[1545827786] 'process raft request' (duration: 185.175734ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:46:29.740371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.044709ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-06-13T22:46:29.9152Z","caller":"traceutil/trace.go:171","msg":"trace[1280358636] linearizableReadLoop","detail":"{readStateIndex:2152; appliedIndex:2151; }","duration":"157.447818ms","start":"2026-06-13T22:46:29.757729Z","end":"2026-06-13T22:46:29.915176Z","steps":["trace[1280358636] 'read index received' (duration: 136.180961ms)","trace[1280358636] 'applied index is now lower than readState.Index' (duration: 21.266027ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:46:29.915323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.58226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-06-13T22:46:29.915403Z","caller":"traceutil/trace.go:171","msg":"trace[710721491] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2070; }","duration":"157.681613ms","start":"2026-06-13T22:46:29.757688Z","end":"2026-06-13T22:46:29.915369Z","steps":["trace[710721491] 'agreement among raft nodes before linearized reading' (duration: 157.576651ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:46:29.915347Z","caller":"traceutil/trace.go:171","msg":"trace[1411362016] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"160.886456ms","start":"2026-06-13T22:46:29.754334Z","end":"2026-06-13T22:46:29.915221Z","steps":["trace[1411362016] 'process raft request' (duration: 139.500836ms)","trace[1411362016] 'compare' (duration: 21.230517ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:47:40.241723Z","caller":"traceutil/trace.go:171","msg":"trace[1899148114] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"100.542479ms","start":"2026-06-13T22:47:40.141153Z","end":"2026-06-13T22:47:40.241696Z","steps":["trace[1899148114] 'process raft request' (duration: 100.059973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:48:13.691285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.955975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:48:13.69143Z","caller":"traceutil/trace.go:171","msg":"trace[706365720] linearizableReadLoop","detail":"{readStateIndex:2837; appliedIndex:2836; }","duration":"190.644065ms","start":"2026-06-13T22:48:13.500775Z","end":"2026-06-13T22:48:13.691419Z","steps":["trace[706365720] 'read index received' (duration: 63.432847ms)","trace[706365720] 'applied index is now lower than readState.Index' (duration: 127.210468ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:48:13.691792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.860538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-13T22:48:13.691812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.261772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-13T22:48:13.691877Z","caller":"traceutil/trace.go:171","msg":"trace[1365101343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2732; }","duration":"176.980539ms","start":"2026-06-13T22:48:13.51488Z","end":"2026-06-13T22:48:13.69186Z","steps":["trace[1365101343] 'agreement among raft nodes before linearized reading' (duration: 176.732866ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:48:13.691894Z","caller":"traceutil/trace.go:171","msg":"trace[1531099377] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2732; }","duration":"156.371064ms","start":"2026-06-13T22:48:13.535502Z","end":"2026-06-13T22:48:13.691873Z","steps":["trace[1531099377] 'agreement among raft nodes before linearized reading' (duration: 156.181522ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:48:13.692062Z","caller":"traceutil/trace.go:171","msg":"trace[65977373] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"232.428263ms","start":"2026-06-13T22:48:13.459602Z","end":"2026-06-13T22:48:13.692031Z","steps":["trace[65977373] 'process raft request' (duration: 104.671158ms)","trace[65977373] 'compare' (duration: 126.838783ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:48:13.692164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.852592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-13T22:48:13.692216Z","caller":"traceutil/trace.go:171","msg":"trace[579155921] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2732; }","duration":"174.914682ms","start":"2026-06-13T22:48:13.517288Z","end":"2026-06-13T22:48:13.692203Z","steps":["trace[579155921] 'agreement among raft nodes before linearized reading' (duration: 174.787561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:48:13.692243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.466766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b8c58facbbc369\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-06-13T22:48:13.692287Z","caller":"traceutil/trace.go:171","msg":"trace[1888188569] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b8c58facbbc369; range_end:; response_count:1; response_revision:2732; }","duration":"191.540286ms","start":"2026-06-13T22:48:13.500735Z","end":"2026-06-13T22:48:13.692275Z","steps":["trace[1888188569] 'agreement among raft nodes before linearized reading' (duration: 191.420885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:48:13.692523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.241916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-13T22:48:13.69258Z","caller":"traceutil/trace.go:171","msg":"trace[1684909067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2732; }","duration":"175.297678ms","start":"2026-06-13T22:48:13.517257Z","end":"2026-06-13T22:48:13.692555Z","steps":["trace[1684909067] 'agreement among raft nodes before linearized reading' (duration: 175.176316ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:43.628011Z","caller":"traceutil/trace.go:171","msg":"trace[43020210] transaction","detail":"{read_only:false; response_revision:4394; number_of_response:1; }","duration":"112.8792ms","start":"2026-06-13T22:51:43.515106Z","end":"2026-06-13T22:51:43.627985Z","steps":["trace[43020210] 'process raft request' (duration: 112.702975ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:43.632112Z","caller":"traceutil/trace.go:171","msg":"trace[626970553] transaction","detail":"{read_only:false; response_revision:4396; number_of_response:1; }","duration":"105.645803ms","start":"2026-06-13T22:51:43.526441Z","end":"2026-06-13T22:51:43.632087Z","steps":["trace[626970553] 'process raft request' (duration: 105.52344ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:43.632157Z","caller":"traceutil/trace.go:171","msg":"trace[2096038256] transaction","detail":"{read_only:false; response_revision:4395; number_of_response:1; }","duration":"116.807225ms","start":"2026-06-13T22:51:43.515327Z","end":"2026-06-13T22:51:43.632134Z","steps":["trace[2096038256] 'process raft request' (duration: 116.223851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:49.024407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.661432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-13T22:51:49.024652Z","caller":"traceutil/trace.go:171","msg":"trace[34922338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4551; }","duration":"157.937768ms","start":"2026-06-13T22:51:48.866697Z","end":"2026-06-13T22:51:49.024635Z","steps":["trace[34922338] 'range keys from in-memory index tree' (duration: 157.508938ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:49.02471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.324402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-06-13T22:51:49.024855Z","caller":"traceutil/trace.go:171","msg":"trace[589174445] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4551; }","duration":"238.544587ms","start":"2026-06-13T22:51:48.786274Z","end":"2026-06-13T22:51:49.024819Z","steps":["trace[589174445] 'range keys from in-memory index tree' (duration: 238.060626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:49.025096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.122625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-13T22:51:49.025194Z","caller":"traceutil/trace.go:171","msg":"trace[734619025] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4551; }","duration":"171.474724ms","start":"2026-06-13T22:51:48.853698Z","end":"2026-06-13T22:51:49.025173Z","steps":["trace[734619025] 'range keys from in-memory index tree' (duration: 170.701286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:54.504307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.456437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-13T22:51:54.504434Z","caller":"traceutil/trace.go:171","msg":"trace[760928121] linearizableReadLoop","detail":"{readStateIndex:4839; appliedIndex:4838; }","duration":"170.43787ms","start":"2026-06-13T22:51:54.333976Z","end":"2026-06-13T22:51:54.504414Z","steps":["trace[760928121] 'read index received' (duration: 54.758538ms)","trace[760928121] 'applied index is now lower than readState.Index' (duration: 115.677462ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:54.504531Z","caller":"traceutil/trace.go:171","msg":"trace[1732946121] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"172.151951ms","start":"2026-06-13T22:51:54.332364Z","end":"2026-06-13T22:51:54.504516Z","steps":["trace[1732946121] 'process raft request' (duration: 56.411927ms)","trace[1732946121] 'compare' (duration: 115.13851ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:51:54.504776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.784969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-06-13T22:51:54.504839Z","caller":"traceutil/trace.go:171","msg":"trace[1320884777] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4659; }","duration":"170.86304ms","start":"2026-06-13T22:51:54.333961Z","end":"2026-06-13T22:51:54.504824Z","steps":["trace[1320884777] 'agreement among raft nodes before linearized reading' (duration: 170.593194ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:54.722054Z","caller":"traceutil/trace.go:171","msg":"trace[1480253628] linearizableReadLoop","detail":"{readStateIndex:4841; appliedIndex:4840; }","duration":"151.516492ms","start":"2026-06-13T22:51:54.570515Z","end":"2026-06-13T22:51:54.722032Z","steps":["trace[1480253628] 'read index received' (duration: 151.400289ms)","trace[1480253628] 'applied index is now lower than readState.Index' (duration: 115.272µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:54.72212Z","caller":"traceutil/trace.go:171","msg":"trace[978345782] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"161.96767ms","start":"2026-06-13T22:51:54.56011Z","end":"2026-06-13T22:51:54.722078Z","steps":["trace[978345782] 'process raft request' (duration: 161.800336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:54.722227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.698597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-06-13T22:51:54.722269Z","caller":"traceutil/trace.go:171","msg":"trace[942692594] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4661; }","duration":"151.794539ms","start":"2026-06-13T22:51:54.570463Z","end":"2026-06-13T22:51:54.722258Z","steps":["trace[942692594] 'agreement among raft nodes before linearized reading' (duration: 151.661636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:54.753735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.628681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-06-13T22:51:54.753911Z","caller":"traceutil/trace.go:171","msg":"trace[1565489763] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4663; }","duration":"177.827306ms","start":"2026-06-13T22:51:54.576058Z","end":"2026-06-13T22:51:54.753885Z","steps":["trace[1565489763] 'agreement among raft nodes before linearized reading' (duration: 177.523488ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:51:55.06421Z","caller":"traceutil/trace.go:171","msg":"trace[1502808750] transaction","detail":"{read_only:false; response_revision:4675; number_of_response:1; }","duration":"167.214321ms","start":"2026-06-13T22:51:54.896976Z","end":"2026-06-13T22:51:55.06419Z","steps":["trace[1502808750] 'process raft request' (duration: 132.909279ms)","trace[1502808750] 'compare' (duration: 34.020795ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:55.287453Z","caller":"traceutil/trace.go:171","msg":"trace[216802481] linearizableReadLoop","detail":"{readStateIndex:4858; appliedIndex:4856; }","duration":"159.794809ms","start":"2026-06-13T22:51:55.127636Z","end":"2026-06-13T22:51:55.28743Z","steps":["trace[216802481] 'read index received' (duration: 2.871508ms)","trace[216802481] 'applied index is now lower than readState.Index' (duration: 156.922451ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:55.287883Z","caller":"traceutil/trace.go:171","msg":"trace[1830429715] transaction","detail":"{read_only:false; response_revision:4677; number_of_response:1; }","duration":"178.166754ms","start":"2026-06-13T22:51:55.109682Z","end":"2026-06-13T22:51:55.287849Z","steps":["trace[1830429715] 'process raft request' (duration: 115.137423ms)","trace[1830429715] 'compare' (duration: 62.002487ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:51:55.28812Z","caller":"traceutil/trace.go:171","msg":"trace[1727303143] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"159.492472ms","start":"2026-06-13T22:51:55.128593Z","end":"2026-06-13T22:51:55.288085Z","steps":["trace[1727303143] 'process raft request' (duration: 158.753014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:51:55.288335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.68216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-06-13T22:51:55.288378Z","caller":"traceutil/trace.go:171","msg":"trace[1603131650] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4678; }","duration":"160.764152ms","start":"2026-06-13T22:51:55.1276Z","end":"2026-06-13T22:51:55.288364Z","steps":["trace[1603131650] 'agreement among raft nodes before linearized reading' (duration: 159.919242ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:06.963443Z","caller":"traceutil/trace.go:171","msg":"trace[172034083] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"113.548318ms","start":"2026-06-13T22:52:06.849859Z","end":"2026-06-13T22:52:06.963408Z","steps":["trace[172034083] 'process raft request' (duration: 84.14452ms)","trace[172034083] 'compare' (duration: 29.226474ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:52:06.963459Z","caller":"traceutil/trace.go:171","msg":"trace[853824028] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"113.421074ms","start":"2026-06-13T22:52:06.850004Z","end":"2026-06-13T22:52:06.963425Z","steps":["trace[853824028] 'process raft request' (duration: 113.324292ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:06.963507Z","caller":"traceutil/trace.go:171","msg":"trace[1712897149] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"113.24716ms","start":"2026-06-13T22:52:06.850234Z","end":"2026-06-13T22:52:06.963481Z","steps":["trace[1712897149] 'process raft request' (duration: 113.126008ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:39.179468Z","caller":"traceutil/trace.go:171","msg":"trace[922766796] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"110.845797ms","start":"2026-06-13T22:52:39.068592Z","end":"2026-06-13T22:52:39.179438Z","steps":["trace[922766796] 'process raft request' (duration: 110.265554ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:43.611476Z","caller":"traceutil/trace.go:171","msg":"trace[597566239] transaction","detail":"{read_only:false; response_revision:5174; number_of_response:1; }","duration":"209.467819ms","start":"2026-06-13T22:52:43.401982Z","end":"2026-06-13T22:52:43.61145Z","steps":["trace[597566239] 'process raft request' (duration: 209.286275ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:43.632509Z","caller":"traceutil/trace.go:171","msg":"trace[1348113320] linearizableReadLoop","detail":"{readStateIndex:5370; appliedIndex:5368; }","duration":"123.089892ms","start":"2026-06-13T22:52:43.50938Z","end":"2026-06-13T22:52:43.63247Z","steps":["trace[1348113320] 'read index received' (duration: 101.884288ms)","trace[1348113320] 'applied index is now lower than readState.Index' (duration: 21.204094ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:52:43.632847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.42994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-13T22:52:43.632897Z","caller":"traceutil/trace.go:171","msg":"trace[3769662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5175; }","duration":"123.571443ms","start":"2026-06-13T22:52:43.509311Z","end":"2026-06-13T22:52:43.632883Z","steps":["trace[3769662] 'agreement among raft nodes before linearized reading' (duration: 123.326417ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:52:43.632833Z","caller":"traceutil/trace.go:171","msg":"trace[1176383645] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"183.286249ms","start":"2026-06-13T22:52:43.449488Z","end":"2026-06-13T22:52:43.632775Z","steps":["trace[1176383645] 'process raft request' (duration: 182.590084ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:53:36.411929Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2854} {"level":"info","ts":"2026-06-13T22:53:36.497236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2854,"took":"83.117337ms","hash":1355041816,"current-db-size-bytes":53760000,"current-db-size":"54 MB","current-db-size-in-use-bytes":29483008,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-13T22:53:36.497382Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1355041816,"revision":2854,"compact-revision":-1} {"level":"warn","ts":"2026-06-13T22:56:04.828445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.488769ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-13T22:56:04.828608Z","caller":"traceutil/trace.go:171","msg":"trace[2106478408] linearizableReadLoop","detail":"{readStateIndex:6830; appliedIndex:6829; }","duration":"101.855928ms","start":"2026-06-13T22:56:04.726706Z","end":"2026-06-13T22:56:04.828562Z","steps":["trace[2106478408] 'read index received' (duration: 43.791µs)","trace[2106478408] 'applied index is now lower than readState.Index' (duration: 101.810757ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T22:56:04.82908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.365917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-13T22:56:04.829141Z","caller":"traceutil/trace.go:171","msg":"trace[314731017] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6584; }","duration":"102.487659ms","start":"2026-06-13T22:56:04.726639Z","end":"2026-06-13T22:56:04.829127Z","steps":["trace[314731017] 'agreement among raft nodes before linearized reading' (duration: 102.355347ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:56:05.037835Z","caller":"traceutil/trace.go:171","msg":"trace[249528503] linearizableReadLoop","detail":"{readStateIndex:6833; appliedIndex:6831; }","duration":"107.022708ms","start":"2026-06-13T22:56:04.930797Z","end":"2026-06-13T22:56:05.03782Z","steps":["trace[249528503] 'read index received' (duration: 106.841675ms)","trace[249528503] 'applied index is now lower than readState.Index' (duration: 180.323µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T22:56:05.03791Z","caller":"traceutil/trace.go:171","msg":"trace[443197756] transaction","detail":"{read_only:false; response_revision:6586; number_of_response:1; }","duration":"201.65835ms","start":"2026-06-13T22:56:04.836245Z","end":"2026-06-13T22:56:05.037903Z","steps":["trace[443197756] 'process raft request' (duration: 201.425326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T22:56:05.038059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.248812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-13T22:56:05.038143Z","caller":"traceutil/trace.go:171","msg":"trace[1015819530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6587; }","duration":"107.374004ms","start":"2026-06-13T22:56:04.930754Z","end":"2026-06-13T22:56:05.038128Z","steps":["trace[1015819530] 'agreement among raft nodes before linearized reading' (duration: 107.212171ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:56:05.038307Z","caller":"traceutil/trace.go:171","msg":"trace[1549234157] transaction","detail":"{read_only:false; response_revision:6587; number_of_response:1; }","duration":"202.047927ms","start":"2026-06-13T22:56:04.836245Z","end":"2026-06-13T22:56:05.038293Z","steps":["trace[1549234157] 'process raft request' (duration: 201.524177ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T22:58:36.422713Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5578} {"level":"info","ts":"2026-06-13T22:58:36.49099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5578,"took":"66.846711ms","hash":4271277114,"current-db-size-bytes":53760000,"current-db-size":"54 MB","current-db-size-in-use-bytes":18173952,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-13T22:58:36.49104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4271277114,"revision":5578,"compact-revision":2854}