{"level":"warn","ts":"2026-05-05T06:46:44.554158Z","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-05T06:46:44.55426Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-05T06:46:44.554348Z","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-05T06:46:44.55436Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-05-05T06:46:44.554387Z","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-05T06:46:44.555231Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-05-05T06:46:44.555331Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-05T06:46:44.559781Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.163716ms"} {"level":"info","ts":"2026-05-05T06:46:44.567948Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-05-05T06:46:44.568058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T06:46:44.568091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-05-05T06:46:44.568111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T06:46:44.56813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-05-05T06:46:44.568324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-05-05T06:46:44.581744Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T06:46:44.583341Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T06:46:44.585948Z","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-05T06:46:44.588389Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T06:46:44.588549Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T06:46:44.588685Z","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-05T06:46:44.588791Z","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-05T06:46:44.588817Z","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-05T06:46:44.591099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-05-05T06:46:44.591359Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-05-05T06:46:44.593925Z","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-05T06:46:44.594074Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-05-05T06:46:44.594117Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-05-05T06:46:44.595361Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T06:46:44.595407Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T06:46:44.769516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T06:46:44.769616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T06:46:44.769644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-05-05T06:46:44.769672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-05-05T06:46:44.769683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-05-05T06:46:44.7697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-05-05T06:46:44.76972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-05-05T06:46:44.771201Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T06:46:44.771248Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:46:44.771431Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:46:44.771763Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:46:44.77247Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T06:46:44.772627Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T06:46:44.772695Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:46:44.772841Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:46:44.772887Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:46:44.774388Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-05-05T06:46:44.775605Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T06:47:16.644234Z","caller":"traceutil/trace.go:171","msg":"trace[1118242187] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"100.651108ms","start":"2026-05-05T06:47:16.543552Z","end":"2026-05-05T06:47:16.644203Z","steps":["trace[1118242187] 'process raft request' (duration: 86.093503ms)","trace[1118242187] 'compare' (duration: 14.398641ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:47:23.052379Z","caller":"traceutil/trace.go:171","msg":"trace[130103226] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"182.385904ms","start":"2026-05-05T06:47:22.869964Z","end":"2026-05-05T06:47:23.05235Z","steps":["trace[130103226] 'process raft request' (duration: 156.966457ms)","trace[130103226] 'compare' (duration: 25.273633ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:47:23.640581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.781393ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-05-05T06:47:23.640751Z","caller":"traceutil/trace.go:171","msg":"trace[1154196170] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"378.678076ms","start":"2026-05-05T06:47:23.262051Z","end":"2026-05-05T06:47:23.640729Z","steps":["trace[1154196170] 'read index received' (duration: 42.432µs)","trace[1154196170] 'applied index is now lower than readState.Index' (duration: 378.633894ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:47:23.641726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.645252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hdmtf\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T06:47:23.641833Z","caller":"traceutil/trace.go:171","msg":"trace[220035754] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hdmtf; range_end:; response_count:1; response_revision:622; }","duration":"379.800426ms","start":"2026-05-05T06:47:23.262Z","end":"2026-05-05T06:47:23.641801Z","steps":["trace[220035754] 'agreement among raft nodes before linearized reading' (duration: 378.819559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:47:23.641901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:47:23.26198Z","time spent":"379.908931ms","remote":"127.0.0.1:48812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16986,"request content":"key:\"/registry/pods/kube-system/cilium-hdmtf\" "} {"level":"warn","ts":"2026-05-05T06:47:23.642478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.750043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:47:23.64261Z","caller":"traceutil/trace.go:171","msg":"trace[1065606960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"297.934399ms","start":"2026-05-05T06:47:23.344642Z","end":"2026-05-05T06:47:23.642576Z","steps":["trace[1065606960] 'agreement among raft nodes before linearized reading' (duration: 297.715903ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:47:23.642681Z","caller":"traceutil/trace.go:171","msg":"trace[1371497120] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"154.273732ms","start":"2026-05-05T06:47:23.488367Z","end":"2026-05-05T06:47:23.642641Z","steps":["trace[1371497120] 'process raft request' (duration: 152.977407ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:48:05.254538Z","caller":"traceutil/trace.go:171","msg":"trace[42775660] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"186.24969ms","start":"2026-05-05T06:48:05.068256Z","end":"2026-05-05T06:48:05.254506Z","steps":["trace[42775660] 'process raft request' (duration: 185.995224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:48:52.193762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.195291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d1baa4ab-1b9e-4e78-88ec-45ddbd57dd28\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-05T06:48:52.193897Z","caller":"traceutil/trace.go:171","msg":"trace[1081159129] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d1baa4ab-1b9e-4e78-88ec-45ddbd57dd28; range_end:; response_count:1; response_revision:1848; }","duration":"280.359336ms","start":"2026-05-05T06:48:51.913501Z","end":"2026-05-05T06:48:52.193861Z","steps":["trace[1081159129] 'range keys from in-memory index tree' (duration: 279.926673ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:48:52.257546Z","caller":"traceutil/trace.go:171","msg":"trace[565209165] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"142.907539ms","start":"2026-05-05T06:48:52.114593Z","end":"2026-05-05T06:48:52.2575Z","steps":["trace[565209165] 'process raft request' (duration: 142.667252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:03.776566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.730126ms","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-05-05T06:49:03.776668Z","caller":"traceutil/trace.go:171","msg":"trace[1721488666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1949; }","duration":"141.911471ms","start":"2026-05-05T06:49:03.634734Z","end":"2026-05-05T06:49:03.776645Z","steps":["trace[1721488666] 'range keys from in-memory index tree' (duration: 141.50735ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:36.567389Z","caller":"traceutil/trace.go:171","msg":"trace[1329296727] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"116.371771ms","start":"2026-05-05T06:49:36.450975Z","end":"2026-05-05T06:49:36.567347Z","steps":["trace[1329296727] 'process raft request' (duration: 116.154435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:42.212925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.388357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T06:49:42.213093Z","caller":"traceutil/trace.go:171","msg":"trace[605095393] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2170; }","duration":"122.626464ms","start":"2026-05-05T06:49:42.090429Z","end":"2026-05-05T06:49:42.213055Z","steps":["trace[605095393] 'range keys from in-memory index tree' (duration: 122.076039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:43.460041Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880724161227117,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T06:49:43.545173Z","caller":"traceutil/trace.go:171","msg":"trace[1502265194] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"689.647521ms","start":"2026-05-05T06:49:42.855493Z","end":"2026-05-05T06:49:43.54514Z","steps":["trace[1502265194] 'process raft request' (duration: 689.477016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:43.54599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:42.855458Z","time spent":"689.795565ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:49:43.57071Z","caller":"traceutil/trace.go:171","msg":"trace[995525426] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"487.791923ms","start":"2026-05-05T06:49:43.082882Z","end":"2026-05-05T06:49:43.570674Z","steps":["trace[995525426] 'process raft request' (duration: 487.628059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:43.57094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:43.082833Z","time spent":"487.966548ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:49:43.668906Z","caller":"traceutil/trace.go:171","msg":"trace[2060262297] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2250; }","duration":"709.981748ms","start":"2026-05-05T06:49:42.958835Z","end":"2026-05-05T06:49:43.668817Z","steps":["trace[2060262297] 'read index received' (duration: 586.181241ms)","trace[2060262297] 'applied index is now lower than readState.Index' (duration: 123.799067ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:49:43.669263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.090971ms","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-05-05T06:49:43.669324Z","caller":"traceutil/trace.go:171","msg":"trace[839538380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2176; }","duration":"672.202474ms","start":"2026-05-05T06:49:42.997103Z","end":"2026-05-05T06:49:43.669305Z","steps":["trace[839538380] 'agreement among raft nodes before linearized reading' (duration: 671.975358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:43.669362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:42.997085Z","time spent":"672.267095ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T06:49:43.669336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.463892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-05T06:49:43.669488Z","caller":"traceutil/trace.go:171","msg":"trace[116520261] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2176; }","duration":"710.669648ms","start":"2026-05-05T06:49:42.958778Z","end":"2026-05-05T06:49:43.669447Z","steps":["trace[116520261] 'agreement among raft nodes before linearized reading' (duration: 710.276517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:43.669553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:42.958753Z","time spent":"710.789071ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-05T06:49:43.66937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.562307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:49:43.669784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.283107ms","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-05-05T06:49:43.669898Z","caller":"traceutil/trace.go:171","msg":"trace[2104977997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2176; }","duration":"324.159243ms","start":"2026-05-05T06:49:43.345716Z","end":"2026-05-05T06:49:43.669875Z","steps":["trace[2104977997] 'agreement among raft nodes before linearized reading' (duration: 323.503115ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:43.669923Z","caller":"traceutil/trace.go:171","msg":"trace[2070303201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2176; }","duration":"377.634886ms","start":"2026-05-05T06:49:43.292255Z","end":"2026-05-05T06:49:43.66989Z","steps":["trace[2070303201] 'agreement among raft nodes before linearized reading' (duration: 377.04282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:43.669954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:43.345689Z","time spent":"324.251645ms","remote":"127.0.0.1:48642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T06:49:43.669584Z","caller":"traceutil/trace.go:171","msg":"trace[334239352] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"163.777014ms","start":"2026-05-05T06:49:43.505746Z","end":"2026-05-05T06:49:43.669523Z","steps":["trace[334239352] 'process raft request' (duration: 162.844848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:43.669977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:43.292248Z","time spent":"377.714478ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T06:49:43.670008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.251905ms","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-05T06:49:43.67016Z","caller":"traceutil/trace.go:171","msg":"trace[1248106051] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2176; }","duration":"378.419029ms","start":"2026-05-05T06:49:43.291702Z","end":"2026-05-05T06:49:43.670121Z","steps":["trace[1248106051] 'agreement among raft nodes before linearized reading' (duration: 377.546585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:43.67028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:43.291632Z","time spent":"378.586253ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T06:49:43.670622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.481321ms","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-05-05T06:49:43.670929Z","caller":"traceutil/trace.go:171","msg":"trace[2098542282] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2176; }","duration":"451.851721ms","start":"2026-05-05T06:49:43.219047Z","end":"2026-05-05T06:49:43.670899Z","steps":["trace[2098542282] 'agreement among raft nodes before linearized reading' (duration: 450.304767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:43.670983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:43.219025Z","time spent":"451.946333ms","remote":"127.0.0.1:47448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5433,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-05T06:51:12.625361Z","caller":"traceutil/trace.go:171","msg":"trace[1497805841] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"118.06487ms","start":"2026-05-05T06:51:12.507269Z","end":"2026-05-05T06:51:12.625334Z","steps":["trace[1497805841] 'process raft request' (duration: 117.897055ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:55:07.758487Z","caller":"traceutil/trace.go:171","msg":"trace[1483382660] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"111.679269ms","start":"2026-05-05T06:55:07.646777Z","end":"2026-05-05T06:55:07.758457Z","steps":["trace[1483382660] 'process raft request' (duration: 111.369801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:55:16.017689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.931432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:55:16.017806Z","caller":"traceutil/trace.go:171","msg":"trace[430996630] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"310.990292ms","start":"2026-05-05T06:55:15.706796Z","end":"2026-05-05T06:55:16.017786Z","steps":["trace[430996630] 'process raft request' (duration: 55.880136ms)","trace[430996630] 'compare' (duration: 254.798077ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:55:16.017865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:55:15.706776Z","time spent":"311.060055ms","remote":"127.0.0.1:48812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3703,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:55:16.02676Z","caller":"traceutil/trace.go:171","msg":"trace[309996568] linearizableReadLoop","detail":"{readStateIndex:5240; appliedIndex:5239; }","duration":"131.349528ms","start":"2026-05-05T06:55:15.895395Z","end":"2026-05-05T06:55:16.026744Z","steps":["trace[309996568] 'read index received' (duration: 131.312147ms)","trace[309996568] 'applied index is now lower than readState.Index' (duration: 36.361µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:55:16.026785Z","caller":"traceutil/trace.go:171","msg":"trace[1447063726] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"319.851879ms","start":"2026-05-05T06:55:15.70691Z","end":"2026-05-05T06:55:16.026762Z","steps":["trace[1447063726] 'process raft request' (duration: 319.739965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:55:16.026927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.521843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-05T06:55:16.026929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:55:15.706882Z","time spent":"319.960422ms","remote":"127.0.0.1:48706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:55:16.026956Z","caller":"traceutil/trace.go:171","msg":"trace[1692804599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5052; }","duration":"131.599005ms","start":"2026-05-05T06:55:15.89535Z","end":"2026-05-05T06:55:16.026949Z","steps":["trace[1692804599] 'agreement among raft nodes before linearized reading' (duration: 131.455281ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:55:29.966789Z","caller":"traceutil/trace.go:171","msg":"trace[959109292] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"104.150761ms","start":"2026-05-05T06:55:29.86254Z","end":"2026-05-05T06:55:29.966691Z","steps":["trace[959109292] 'process raft request' (duration: 103.75174ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:55:35.804159Z","caller":"traceutil/trace.go:171","msg":"trace[457644239] linearizableReadLoop","detail":"{readStateIndex:5662; appliedIndex:5661; }","duration":"137.374222ms","start":"2026-05-05T06:55:35.666753Z","end":"2026-05-05T06:55:35.804127Z","steps":["trace[457644239] 'read index received' (duration: 137.214137ms)","trace[457644239] 'applied index is now lower than readState.Index' (duration: 158.695µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:55:35.804411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.6468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T06:55:35.804425Z","caller":"traceutil/trace.go:171","msg":"trace[1128716907] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"187.341398ms","start":"2026-05-05T06:55:35.617061Z","end":"2026-05-05T06:55:35.804402Z","steps":["trace[1128716907] 'process raft request' (duration: 186.864984ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:55:35.804467Z","caller":"traceutil/trace.go:171","msg":"trace[570746667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5461; }","duration":"137.761813ms","start":"2026-05-05T06:55:35.666691Z","end":"2026-05-05T06:55:35.804452Z","steps":["trace[570746667] 'agreement among raft nodes before linearized reading' (duration: 137.572828ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:55:36.054464Z","caller":"traceutil/trace.go:171","msg":"trace[715652156] linearizableReadLoop","detail":"{readStateIndex:5664; appliedIndex:5663; }","duration":"123.565363ms","start":"2026-05-05T06:55:35.930876Z","end":"2026-05-05T06:55:36.054441Z","steps":["trace[715652156] 'read index received' (duration: 109.653861ms)","trace[715652156] 'applied index is now lower than readState.Index' (duration: 13.910422ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:55:36.054565Z","caller":"traceutil/trace.go:171","msg":"trace[1465632434] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"129.945388ms","start":"2026-05-05T06:55:35.92458Z","end":"2026-05-05T06:55:36.054525Z","steps":["trace[1465632434] 'process raft request' (duration: 115.746046ms)","trace[1465632434] 'compare' (duration: 14.000715ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:55:36.054785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.892763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-05-05T06:55:36.054852Z","caller":"traceutil/trace.go:171","msg":"trace[825720150] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5463; }","duration":"124.026317ms","start":"2026-05-05T06:55:35.930814Z","end":"2026-05-05T06:55:36.05484Z","steps":["trace[825720150] 'agreement among raft nodes before linearized reading' (duration: 123.729618ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:56:26.445575Z","caller":"traceutil/trace.go:171","msg":"trace[442774614] transaction","detail":"{read_only:false; response_revision:5954; number_of_response:1; }","duration":"105.26258ms","start":"2026-05-05T06:56:26.340288Z","end":"2026-05-05T06:56:26.445551Z","steps":["trace[442774614] 'process raft request' (duration: 21.694458ms)","trace[442774614] 'compare' (duration: 83.461079ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:56:44.990885Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3029} {"level":"info","ts":"2026-05-05T06:56:45.070935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3029,"took":"78.737695ms","hash":260632988,"current-db-size-bytes":57315328,"current-db-size":"57 MB","current-db-size-in-use-bytes":31715328,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T06:56:45.071013Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":260632988,"revision":3029,"compact-revision":-1} {"level":"info","ts":"2026-05-05T07:01:44.998164Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6109} {"level":"info","ts":"2026-05-05T07:01:45.094759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6109,"took":"94.627155ms","hash":451975631,"current-db-size-bytes":57315328,"current-db-size":"57 MB","current-db-size-in-use-bytes":20811776,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-05T07:01:45.094812Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":451975631,"revision":6109,"compact-revision":3029} {"level":"warn","ts":"2026-05-05T07:02:30.299656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.45968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T07:02:30.299702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.426136ms","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-05-05T07:02:30.299765Z","caller":"traceutil/trace.go:171","msg":"trace[37729047] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8532; }","duration":"303.632075ms","start":"2026-05-05T07:02:29.996113Z","end":"2026-05-05T07:02:30.299745Z","steps":["trace[37729047] 'count revisions from in-memory index tree' (duration: 303.315046ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:30.2998Z","caller":"traceutil/trace.go:171","msg":"trace[326440862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8532; }","duration":"521.57007ms","start":"2026-05-05T07:02:29.778205Z","end":"2026-05-05T07:02:30.299775Z","steps":["trace[326440862] 'range keys from in-memory index tree' (duration: 521.184979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:02:30.299859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:02:29.778185Z","time spent":"521.664673ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T07:02:30.300106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.410112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T07:02:30.300232Z","caller":"traceutil/trace.go:171","msg":"trace[347749968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8532; }","duration":"438.575916ms","start":"2026-05-05T07:02:29.861621Z","end":"2026-05-05T07:02:30.300197Z","steps":["trace[347749968] 'range keys from in-memory index tree' (duration: 438.181475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:02:30.300245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:02:29.996083Z","time spent":"303.721087ms","remote":"127.0.0.1:52678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:02:30.300296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:02:29.861601Z","time spent":"438.68286ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T07:02:41.765963Z","caller":"traceutil/trace.go:171","msg":"trace[1146887494] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"141.18665ms","start":"2026-05-05T07:02:41.624753Z","end":"2026-05-05T07:02:41.76594Z","steps":["trace[1146887494] 'process raft request' (duration: 141.048936ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:41.906635Z","caller":"traceutil/trace.go:171","msg":"trace[1081890874] linearizableReadLoop","detail":"{readStateIndex:8897; appliedIndex:8895; }","duration":"193.736546ms","start":"2026-05-05T07:02:41.71286Z","end":"2026-05-05T07:02:41.906596Z","steps":["trace[1081890874] 'read index received' (duration: 52.940818ms)","trace[1081890874] 'applied index is now lower than readState.Index' (duration: 140.794438ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:02:41.906807Z","caller":"traceutil/trace.go:171","msg":"trace[1515671372] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"200.865999ms","start":"2026-05-05T07:02:41.705927Z","end":"2026-05-05T07:02:41.906793Z","steps":["trace[1515671372] 'process raft request' (duration: 189.929677ms)","trace[1515671372] 'compare' (duration: 10.549931ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:02:41.906981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.254181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T07:02:41.907075Z","caller":"traceutil/trace.go:171","msg":"trace[1545826477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8593; }","duration":"155.355933ms","start":"2026-05-05T07:02:41.751699Z","end":"2026-05-05T07:02:41.907055Z","steps":["trace[1545826477] 'agreement among raft nodes before linearized reading' (duration: 155.151428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:02:41.906982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.132148ms","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-05T07:02:41.907185Z","caller":"traceutil/trace.go:171","msg":"trace[442976952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8593; }","duration":"194.374324ms","start":"2026-05-05T07:02:41.712783Z","end":"2026-05-05T07:02:41.907158Z","steps":["trace[442976952] 'agreement among raft nodes before linearized reading' (duration: 194.072236ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:42.481989Z","caller":"traceutil/trace.go:171","msg":"trace[670546531] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"122.626022ms","start":"2026-05-05T07:02:42.359342Z","end":"2026-05-05T07:02:42.481968Z","steps":["trace[670546531] 'process raft request' (duration: 122.474777ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:42.71622Z","caller":"traceutil/trace.go:171","msg":"trace[2144522223] linearizableReadLoop","detail":"{readStateIndex:8901; appliedIndex:8899; }","duration":"337.870809ms","start":"2026-05-05T07:02:42.378325Z","end":"2026-05-05T07:02:42.716196Z","steps":["trace[2144522223] 'read index received' (duration: 103.424844ms)","trace[2144522223] 'applied index is now lower than readState.Index' (duration: 234.444875ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:02:42.716361Z","caller":"traceutil/trace.go:171","msg":"trace[1677088342] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"291.53697ms","start":"2026-05-05T07:02:42.424808Z","end":"2026-05-05T07:02:42.716345Z","steps":["trace[1677088342] 'process raft request' (duration: 291.343755ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:02:42.716343Z","caller":"traceutil/trace.go:171","msg":"trace[1930495905] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"355.060109ms","start":"2026-05-05T07:02:42.361246Z","end":"2026-05-05T07:02:42.716306Z","steps":["trace[1930495905] 'process raft request' (duration: 354.7204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:02:42.716552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:02:42.361227Z","time spent":"355.206694ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:02:42.716318Z","caller":"traceutil/trace.go:171","msg":"trace[815339164] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"294.846055ms","start":"2026-05-05T07:02:42.421407Z","end":"2026-05-05T07:02:42.716253Z","steps":["trace[815339164] 'process raft request' (duration: 294.706691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:02:42.716588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.802738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:02:42.716694Z","caller":"traceutil/trace.go:171","msg":"trace[1450889989] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8599; }","duration":"265.956953ms","start":"2026-05-05T07:02:42.450715Z","end":"2026-05-05T07:02:42.716672Z","steps":["trace[1450889989] 'agreement among raft nodes before linearized reading' (duration: 265.783238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:02:42.71683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.902093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-768fc64fd6-mnv29.18ac9954cc8ef4bc\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-05-05T07:02:42.716907Z","caller":"traceutil/trace.go:171","msg":"trace[1519199200] range","detail":"{range_begin:/registry/events/openstack/barbican-api-768fc64fd6-mnv29.18ac9954cc8ef4bc; range_end:; response_count:1; response_revision:8599; }","duration":"323.008716ms","start":"2026-05-05T07:02:42.393873Z","end":"2026-05-05T07:02:42.716881Z","steps":["trace[1519199200] 'agreement among raft nodes before linearized reading' (duration: 322.577324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:02:42.71668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.312541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-05T07:02:42.716947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:02:42.393858Z","time spent":"323.080888ms","remote":"127.0.0.1:48706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1156,"request content":"key:\"/registry/events/openstack/barbican-api-768fc64fd6-mnv29.18ac9954cc8ef4bc\" "} {"level":"info","ts":"2026-05-05T07:02:42.717015Z","caller":"traceutil/trace.go:171","msg":"trace[491921287] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8599; }","duration":"338.676742ms","start":"2026-05-05T07:02:42.378275Z","end":"2026-05-05T07:02:42.716951Z","steps":["trace[491921287] 'agreement among raft nodes before linearized reading' (duration: 338.164828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:02:42.71715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:02:42.378256Z","time spent":"338.881118ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T07:03:11.306262Z","caller":"traceutil/trace.go:171","msg":"trace[1654955011] transaction","detail":"{read_only:false; response_revision:8803; number_of_response:1; }","duration":"437.143286ms","start":"2026-05-05T07:03:10.869085Z","end":"2026-05-05T07:03:11.306228Z","steps":["trace[1654955011] 'process raft request' (duration: 436.90017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:11.307133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:03:10.869059Z","time spent":"437.950889ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:03:11.307175Z","caller":"traceutil/trace.go:171","msg":"trace[548422585] linearizableReadLoop","detail":"{readStateIndex:9116; appliedIndex:9114; }","duration":"405.226968ms","start":"2026-05-05T07:03:10.901917Z","end":"2026-05-05T07:03:11.307144Z","steps":["trace[548422585] 'read index received' (duration: 404.188599ms)","trace[548422585] 'applied index is now lower than readState.Index' (duration: 1.036449ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:03:11.307302Z","caller":"traceutil/trace.go:171","msg":"trace[1735366665] transaction","detail":"{read_only:false; response_revision:8804; number_of_response:1; }","duration":"422.076067ms","start":"2026-05-05T07:03:10.885193Z","end":"2026-05-05T07:03:11.307269Z","steps":["trace[1735366665] 'process raft request' (duration: 421.766218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:11.307473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:03:10.885178Z","time spent":"422.21241ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:03:11.307522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.577858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T07:03:11.307645Z","caller":"traceutil/trace.go:171","msg":"trace[1995663646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8804; }","duration":"405.751202ms","start":"2026-05-05T07:03:10.901864Z","end":"2026-05-05T07:03:11.307616Z","steps":["trace[1995663646] 'agreement among raft nodes before linearized reading' (duration: 405.429223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:03:11.307702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:03:10.901846Z","time spent":"405.845316ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T07:03:15.066391Z","caller":"traceutil/trace.go:171","msg":"trace[988927148] linearizableReadLoop","detail":"{readStateIndex:9138; appliedIndex:9136; }","duration":"183.35556ms","start":"2026-05-05T07:03:14.883014Z","end":"2026-05-05T07:03:15.066369Z","steps":["trace[988927148] 'read index received' (duration: 33.363989ms)","trace[988927148] 'applied index is now lower than readState.Index' (duration: 149.990301ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:03:15.066513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.488284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:03:15.066554Z","caller":"traceutil/trace.go:171","msg":"trace[1416101565] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:8824; }","duration":"183.603348ms","start":"2026-05-05T07:03:14.882939Z","end":"2026-05-05T07:03:15.066542Z","steps":["trace[1416101565] 'agreement among raft nodes before linearized reading' (duration: 183.517995ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:15.066557Z","caller":"traceutil/trace.go:171","msg":"trace[663089878] transaction","detail":"{read_only:false; response_revision:8824; number_of_response:1; }","duration":"231.637576ms","start":"2026-05-05T07:03:14.834874Z","end":"2026-05-05T07:03:15.066511Z","steps":["trace[663089878] 'process raft request' (duration: 215.98529ms)","trace[663089878] 'compare' (duration: 15.418669ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:03:23.896031Z","caller":"traceutil/trace.go:171","msg":"trace[1254752824] transaction","detail":"{read_only:false; response_revision:8911; number_of_response:1; }","duration":"203.208296ms","start":"2026-05-05T07:03:23.692798Z","end":"2026-05-05T07:03:23.896007Z","steps":["trace[1254752824] 'process raft request' (duration: 203.041442ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:03:24.44839Z","caller":"traceutil/trace.go:171","msg":"trace[1499909080] transaction","detail":"{read_only:false; response_revision:8919; number_of_response:1; }","duration":"100.337057ms","start":"2026-05-05T07:03:24.348022Z","end":"2026-05-05T07:03:24.448359Z","steps":["trace[1499909080] 'process raft request' (duration: 28.80571ms)","trace[1499909080] 'compare' (duration: 71.363182ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:03:39.850922Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a9.wal"} {"level":"info","ts":"2026-05-05T07:04:29.390045Z","caller":"traceutil/trace.go:171","msg":"trace[556408706] transaction","detail":"{read_only:false; response_revision:9597; number_of_response:1; }","duration":"156.660199ms","start":"2026-05-05T07:04:29.233357Z","end":"2026-05-05T07:04:29.390017Z","steps":["trace[556408706] 'process raft request' (duration: 156.502115ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:04:40.6363Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T07:04:40.641122Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T07:04:40.641294Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T07:06:45.004571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8095} {"level":"info","ts":"2026-05-05T07:06:45.067511Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8095,"took":"60.592831ms","hash":1018190094,"current-db-size-bytes":57315328,"current-db-size":"57 MB","current-db-size-in-use-bytes":28364800,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T07:06:45.067561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1018190094,"revision":8095,"compact-revision":6109} {"level":"warn","ts":"2026-05-05T07:06:58.165061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.835214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:06:58.165157Z","caller":"traceutil/trace.go:171","msg":"trace[224237977] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:10712; }","duration":"107.992448ms","start":"2026-05-05T07:06:58.057145Z","end":"2026-05-05T07:06:58.165137Z","steps":["trace[224237977] 'range keys from in-memory index tree' (duration: 107.73914ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:06:58.612493Z","caller":"traceutil/trace.go:171","msg":"trace[553742602] transaction","detail":"{read_only:false; response_revision:10716; number_of_response:1; }","duration":"120.740941ms","start":"2026-05-05T07:06:58.491718Z","end":"2026-05-05T07:06:58.612459Z","steps":["trace[553742602] 'process raft request' (duration: 72.238955ms)","trace[553742602] 'compare' (duration: 48.356972ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:08:16.458709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.908685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:08:16.458833Z","caller":"traceutil/trace.go:171","msg":"trace[1738285235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11476; }","duration":"113.10081ms","start":"2026-05-05T07:08:16.34571Z","end":"2026-05-05T07:08:16.458811Z","steps":["trace[1738285235] 'range keys from in-memory index tree' (duration: 112.693357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:16.459684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.745458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T07:08:16.459745Z","caller":"traceutil/trace.go:171","msg":"trace[1583534708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11476; }","duration":"131.860792ms","start":"2026-05-05T07:08:16.327871Z","end":"2026-05-05T07:08:16.459732Z","steps":["trace[1583534708] 'range keys from in-memory index tree' (duration: 131.533402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:21.984447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.511341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T07:08:21.984554Z","caller":"traceutil/trace.go:171","msg":"trace[709576631] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:11508; }","duration":"137.682626ms","start":"2026-05-05T07:08:21.846847Z","end":"2026-05-05T07:08:21.98453Z","steps":["trace[709576631] 'count revisions from in-memory index tree' (duration: 137.416769ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:08:22.385064Z","caller":"traceutil/trace.go:171","msg":"trace[1235292246] transaction","detail":"{read_only:false; response_revision:11510; number_of_response:1; }","duration":"104.464674ms","start":"2026-05-05T07:08:22.280566Z","end":"2026-05-05T07:08:22.385031Z","steps":["trace[1235292246] 'process raft request' (duration: 73.29126ms)","trace[1235292246] 'compare' (duration: 31.001379ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:08:23.762569Z","caller":"traceutil/trace.go:171","msg":"trace[1158361827] linearizableReadLoop","detail":"{readStateIndex:11907; appliedIndex:11906; }","duration":"129.781112ms","start":"2026-05-05T07:08:23.632751Z","end":"2026-05-05T07:08:23.762532Z","steps":["trace[1158361827] 'read index received' (duration: 111.439931ms)","trace[1158361827] 'applied index is now lower than readState.Index' (duration: 18.339041ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:08:23.762875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.097201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T07:08:23.762964Z","caller":"traceutil/trace.go:171","msg":"trace[1766194631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11515; }","duration":"130.251296ms","start":"2026-05-05T07:08:23.632692Z","end":"2026-05-05T07:08:23.762943Z","steps":["trace[1766194631] 'agreement among raft nodes before linearized reading' (duration: 129.964047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:08:23.762993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.969754ms","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-05T07:08:23.763112Z","caller":"traceutil/trace.go:171","msg":"trace[1797754888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11515; }","duration":"120.116758ms","start":"2026-05-05T07:08:23.642967Z","end":"2026-05-05T07:08:23.763083Z","steps":["trace[1797754888] 'agreement among raft nodes before linearized reading' (duration: 119.81287ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:11:00.245158Z","caller":"traceutil/trace.go:171","msg":"trace[1434471346] transaction","detail":"{read_only:false; response_revision:12564; number_of_response:1; }","duration":"261.359306ms","start":"2026-05-05T07:10:59.983702Z","end":"2026-05-05T07:11:00.245061Z","steps":["trace[1434471346] 'process raft request' (duration: 261.044077ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:11:45.012286Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10557} {"level":"info","ts":"2026-05-05T07:11:45.099604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10557,"took":"84.59307ms","hash":4104228532,"current-db-size-bytes":57315328,"current-db-size":"57 MB","current-db-size-in-use-bytes":27475968,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T07:11:45.099685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4104228532,"revision":10557,"compact-revision":8095} {"level":"info","ts":"2026-05-05T07:16:45.019826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12815} {"level":"info","ts":"2026-05-05T07:16:45.105849Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12815,"took":"81.273915ms","hash":3976126802,"current-db-size-bytes":57315328,"current-db-size":"57 MB","current-db-size-in-use-bytes":25698304,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T07:16:45.105918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3976126802,"revision":12815,"compact-revision":10557} {"level":"info","ts":"2026-05-05T07:21:45.025446Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14685} {"level":"info","ts":"2026-05-05T07:21:45.104967Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14685,"took":"76.883352ms","hash":1187295349,"current-db-size-bytes":57315328,"current-db-size":"57 MB","current-db-size-in-use-bytes":21233664,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-05T07:21:45.105035Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1187295349,"revision":14685,"compact-revision":12815} {"level":"info","ts":"2026-05-05T07:26:45.031126Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16206} {"level":"info","ts":"2026-05-05T07:26:45.094625Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16206,"took":"61.631508ms","hash":639172755,"current-db-size-bytes":57315328,"current-db-size":"57 MB","current-db-size-in-use-bytes":20492288,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:26:45.094696Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":639172755,"revision":16206,"compact-revision":14685} {"level":"info","ts":"2026-05-05T07:31:45.037094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17728} {"level":"info","ts":"2026-05-05T07:31:45.100232Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17728,"took":"61.196636ms","hash":1087093124,"current-db-size-bytes":57315328,"current-db-size":"57 MB","current-db-size-in-use-bytes":20508672,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:31:45.100289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1087093124,"revision":17728,"compact-revision":16206} {"level":"info","ts":"2026-05-05T07:31:55.43495Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T07:31:55.441728Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-05T07:31:55.441908Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-05-05T07:33:04.187094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880724161274108,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T07:33:04.687666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880724161274108,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T07:33:05.188702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880724161274108,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T07:33:05.688993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880724161274108,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T07:33:05.778753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.152065447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-05T07:33:05.778864Z","caller":"traceutil/trace.go:171","msg":"trace[1276244097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19670; }","duration":"2.152232441s","start":"2026-05-05T07:33:03.626605Z","end":"2026-05-05T07:33:05.778838Z","steps":["trace[1276244097] 'range keys from in-memory index tree' (duration: 2.151852422s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.778919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:03.626557Z","time spent":"2.152347595s","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T07:33:05.779322Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.140266913s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T07:33:05.779796Z","caller":"traceutil/trace.go:171","msg":"trace[1201498741] transaction","detail":"{read_only:false; response_revision:19671; number_of_response:1; }","duration":"2.140849849s","start":"2026-05-05T07:33:03.638922Z","end":"2026-05-05T07:33:05.779772Z","steps":["trace[1201498741] 'process raft request' (duration: 2.140696234s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.780011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:03.638896Z","time spent":"2.141041035s","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:33:05.783393Z","caller":"traceutil/trace.go:171","msg":"trace[1018605924] transaction","detail":"{read_only:false; response_revision:19672; number_of_response:1; }","duration":"2.124511656s","start":"2026-05-05T07:33:03.658866Z","end":"2026-05-05T07:33:05.783378Z","steps":["trace[1018605924] 'process raft request' (duration: 2.124396303s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.783592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:03.658842Z","time spent":"2.124595338s","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:33:05.783641Z","caller":"traceutil/trace.go:171","msg":"trace[1684849575] transaction","detail":"{read_only:false; response_revision:19673; number_of_response:1; }","duration":"1.420965307s","start":"2026-05-05T07:33:04.36265Z","end":"2026-05-05T07:33:05.783615Z","steps":["trace[1684849575] 'process raft request' (duration: 1.420692669s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.783786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:04.362631Z","time spent":"1.421076361s","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:33:05.783937Z","caller":"traceutil/trace.go:171","msg":"trace[741209123] linearizableReadLoop","detail":"{readStateIndex:20388; appliedIndex:20382; }","duration":"2.097899341s","start":"2026-05-05T07:33:03.686024Z","end":"2026-05-05T07:33:05.783924Z","steps":["trace[741209123] 'read index received' (duration: 2.093525356s)","trace[741209123] 'applied index is now lower than readState.Index' (duration: 4.373075ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:33:05.784017Z","caller":"traceutil/trace.go:171","msg":"trace[1862874292] transaction","detail":"{read_only:false; response_revision:19675; number_of_response:1; }","duration":"625.302487ms","start":"2026-05-05T07:33:05.158702Z","end":"2026-05-05T07:33:05.784004Z","steps":["trace[1862874292] 'process raft request' (duration: 625.132952ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:33:05.784003Z","caller":"traceutil/trace.go:171","msg":"trace[227344022] transaction","detail":"{read_only:false; response_revision:19674; number_of_response:1; }","duration":"1.420379731s","start":"2026-05-05T07:33:04.363597Z","end":"2026-05-05T07:33:05.783977Z","steps":["trace[227344022] 'process raft request' (duration: 1.419946709s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.784082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:05.158685Z","time spent":"625.358949ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:33:05.784078Z","caller":"traceutil/trace.go:171","msg":"trace[722597237] transaction","detail":"{read_only:false; response_revision:19676; number_of_response:1; }","duration":"409.050234ms","start":"2026-05-05T07:33:05.374983Z","end":"2026-05-05T07:33:05.784033Z","steps":["trace[722597237] 'process raft request' (duration: 408.90251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.784135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:04.363568Z","time spent":"1.420495405s","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:33:05.784231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:05.37496Z","time spent":"409.189967ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:33:05.784279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.09825593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:33:05.784333Z","caller":"traceutil/trace.go:171","msg":"trace[801663855] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:19676; }","duration":"2.098317263s","start":"2026-05-05T07:33:03.685996Z","end":"2026-05-05T07:33:05.784314Z","steps":["trace[801663855] 'agreement among raft nodes before linearized reading' (duration: 2.098248571s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.784404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:03.685989Z","time spent":"2.098395855s","remote":"127.0.0.1:47314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:33:05.785656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441090359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T07:33:05.785781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.060143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-05T07:33:05.785841Z","caller":"traceutil/trace.go:171","msg":"trace[2111413635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19677; }","duration":"1.441277464s","start":"2026-05-05T07:33:04.344508Z","end":"2026-05-05T07:33:05.785785Z","steps":["trace[2111413635] 'agreement among raft nodes before linearized reading' (duration: 1.439914935s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.785914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:04.344482Z","time spent":"1.441414438s","remote":"127.0.0.1:48642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T07:33:05.785921Z","caller":"traceutil/trace.go:171","msg":"trace[506037828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19677; }","duration":"599.198477ms","start":"2026-05-05T07:33:05.186662Z","end":"2026-05-05T07:33:05.78586Z","steps":["trace[506037828] 'agreement among raft nodes before linearized reading' (duration: 598.895039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.785988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:05.186644Z","time spent":"599.335951ms","remote":"127.0.0.1:48868","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":"warn","ts":"2026-05-05T07:33:05.786154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375754725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-05T07:33:05.786369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627393783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:33:05.786468Z","caller":"traceutil/trace.go:171","msg":"trace[525609977] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:19677; }","duration":"1.627518877s","start":"2026-05-05T07:33:04.158909Z","end":"2026-05-05T07:33:05.786428Z","steps":["trace[525609977] 'agreement among raft nodes before linearized reading' (duration: 1.62550758s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.786492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.78802701s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T07:33:05.786611Z","caller":"traceutil/trace.go:171","msg":"trace[1409414344] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:19677; }","duration":"1.788152894s","start":"2026-05-05T07:33:03.99841Z","end":"2026-05-05T07:33:05.786563Z","steps":["trace[1409414344] 'agreement among raft nodes before linearized reading' (duration: 1.787424963s)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:33:05.78663Z","caller":"traceutil/trace.go:171","msg":"trace[358869390] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19677; }","duration":"1.375896069s","start":"2026-05-05T07:33:04.410341Z","end":"2026-05-05T07:33:05.786237Z","steps":["trace[358869390] 'agreement among raft nodes before linearized reading' (duration: 1.375262911s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.786522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:04.158889Z","time spent":"1.62761765s","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:33:05.786668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:03.998396Z","time spent":"1.788257146s","remote":"127.0.0.1:48958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":111,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:33:05.786702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:04.410321Z","time spent":"1.376361372s","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T07:33:05.787519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.850214634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mpd5w\" ","response":"range_response_count:1 size:6532"} {"level":"info","ts":"2026-05-05T07:33:05.78761Z","caller":"traceutil/trace.go:171","msg":"trace[849358549] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mpd5w; range_end:; response_count:1; response_revision:19677; }","duration":"1.850326797s","start":"2026-05-05T07:33:03.937248Z","end":"2026-05-05T07:33:05.787575Z","steps":["trace[849358549] 'agreement among raft nodes before linearized reading' (duration: 1.847140877s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.787654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:03.937224Z","time spent":"1.850419739s","remote":"127.0.0.1:48812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-mpd5w\" "} {"level":"warn","ts":"2026-05-05T07:33:05.787649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.720361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-05T07:33:05.788471Z","caller":"traceutil/trace.go:171","msg":"trace[2080945891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19677; }","duration":"466.190344ms","start":"2026-05-05T07:33:05.321887Z","end":"2026-05-05T07:33:05.788077Z","steps":["trace[2080945891] 'agreement among raft nodes before linearized reading' (duration: 462.552501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.788685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:05.321871Z","time spent":"466.653608ms","remote":"127.0.0.1:48868","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-05T07:33:05.790121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.798899329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T07:33:05.790193Z","caller":"traceutil/trace.go:171","msg":"trace[845853314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19677; }","duration":"1.799019622s","start":"2026-05-05T07:33:03.991157Z","end":"2026-05-05T07:33:05.790176Z","steps":["trace[845853314] 'agreement among raft nodes before linearized reading' (duration: 1.79753981s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.790223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:03.991129Z","time spent":"1.799086174s","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T07:33:05.790408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672539504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-05T07:33:05.790509Z","caller":"traceutil/trace.go:171","msg":"trace[41683845] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19677; }","duration":"1.672650017s","start":"2026-05-05T07:33:04.117828Z","end":"2026-05-05T07:33:05.790478Z","steps":["trace[41683845] 'agreement among raft nodes before linearized reading' (duration: 1.666585676s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:05.790585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:04.117812Z","time spent":"1.67275929s","remote":"127.0.0.1:48812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-05T07:33:22.374828Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880724161274338,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T07:33:22.803418Z","caller":"traceutil/trace.go:171","msg":"trace[1272885128] transaction","detail":"{read_only:false; response_revision:19755; number_of_response:1; }","duration":"935.887678ms","start":"2026-05-05T07:33:21.867506Z","end":"2026-05-05T07:33:22.803393Z","steps":["trace[1272885128] 'process raft request' (duration: 935.731894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:22.803587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:21.867465Z","time spent":"936.037582ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:33:22.806525Z","caller":"traceutil/trace.go:171","msg":"trace[593130233] transaction","detail":"{read_only:false; response_revision:19756; number_of_response:1; }","duration":"938.42033ms","start":"2026-05-05T07:33:21.868077Z","end":"2026-05-05T07:33:22.806498Z","steps":["trace[593130233] 'process raft request' (duration: 938.211064ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:33:22.806786Z","caller":"traceutil/trace.go:171","msg":"trace[557085798] transaction","detail":"{read_only:false; response_revision:19757; number_of_response:1; }","duration":"938.027218ms","start":"2026-05-05T07:33:21.868739Z","end":"2026-05-05T07:33:22.806766Z","steps":["trace[557085798] 'process raft request' (duration: 937.704538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:22.806773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:21.868048Z","time spent":"938.620297ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:33:22.806872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:21.868728Z","time spent":"938.097861ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:33:22.806898Z","caller":"traceutil/trace.go:171","msg":"trace[352150958] linearizableReadLoop","detail":"{readStateIndex:20473; appliedIndex:20470; }","duration":"932.748139ms","start":"2026-05-05T07:33:21.874116Z","end":"2026-05-05T07:33:22.806864Z","steps":["trace[352150958] 'read index received' (duration: 929.317841ms)","trace[352150958] 'applied index is now lower than readState.Index' (duration: 3.427978ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T07:33:22.806973Z","caller":"traceutil/trace.go:171","msg":"trace[1295252271] transaction","detail":"{read_only:false; response_revision:19758; number_of_response:1; }","duration":"937.603367ms","start":"2026-05-05T07:33:21.869342Z","end":"2026-05-05T07:33:22.806946Z","steps":["trace[1295252271] 'process raft request' (duration: 937.36942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:22.8071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:21.869314Z","time spent":"937.72718ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:33:22.807157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.133947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-05T07:33:22.807188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.642129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-05T07:33:22.807178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.047047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-05T07:33:22.807234Z","caller":"traceutil/trace.go:171","msg":"trace[955777499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19758; }","duration":"930.706381ms","start":"2026-05-05T07:33:21.876514Z","end":"2026-05-05T07:33:22.80722Z","steps":["trace[955777499] 'agreement among raft nodes before linearized reading' (duration: 930.540937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:22.807232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.596484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T07:33:22.807255Z","caller":"traceutil/trace.go:171","msg":"trace[498664698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19758; }","duration":"933.156341ms","start":"2026-05-05T07:33:21.874078Z","end":"2026-05-05T07:33:22.807235Z","steps":["trace[498664698] 'agreement among raft nodes before linearized reading' (duration: 932.932584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:22.807281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:21.876507Z","time spent":"930.765333ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T07:33:22.807291Z","caller":"traceutil/trace.go:171","msg":"trace[19749786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19758; }","duration":"461.705868ms","start":"2026-05-05T07:33:22.345569Z","end":"2026-05-05T07:33:22.807275Z","steps":["trace[19749786] 'agreement among raft nodes before linearized reading' (duration: 461.619045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:22.807297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:21.874066Z","time spent":"933.222472ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T07:33:22.807324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:22.345541Z","time spent":"461.776819ms","remote":"127.0.0.1:48642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T07:33:22.807346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.939411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-05T07:33:22.807332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.846275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T07:33:22.807388Z","caller":"traceutil/trace.go:171","msg":"trace[496793212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19758; }","duration":"721.019804ms","start":"2026-05-05T07:33:22.086359Z","end":"2026-05-05T07:33:22.807379Z","steps":["trace[496793212] 'agreement among raft nodes before linearized reading' (duration: 720.90118ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:33:22.807231Z","caller":"traceutil/trace.go:171","msg":"trace[1044859997] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19758; }","duration":"690.277661ms","start":"2026-05-05T07:33:22.116936Z","end":"2026-05-05T07:33:22.807214Z","steps":["trace[1044859997] 'agreement among raft nodes before linearized reading' (duration: 690.124307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:22.807412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:22.086337Z","time spent":"721.069875ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T07:33:22.807407Z","caller":"traceutil/trace.go:171","msg":"trace[1492107686] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:19758; }","duration":"624.958618ms","start":"2026-05-05T07:33:22.182431Z","end":"2026-05-05T07:33:22.80739Z","steps":["trace[1492107686] 'agreement among raft nodes before linearized reading' (duration: 624.830464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:22.807437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.325892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T07:33:22.807448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:22.116912Z","time spent":"690.513758ms","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:33:22.807475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:22.182413Z","time spent":"625.042571ms","remote":"127.0.0.1:49162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":43,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-05T07:33:22.807237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.58566ms","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:6"} {"level":"info","ts":"2026-05-05T07:33:22.807504Z","caller":"traceutil/trace.go:171","msg":"trace[1615981268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19758; }","duration":"929.437896ms","start":"2026-05-05T07:33:21.878049Z","end":"2026-05-05T07:33:22.807487Z","steps":["trace[1615981268] 'agreement among raft nodes before linearized reading' (duration: 929.22675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:22.807571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:21.878025Z","time spent":"929.531548ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T07:33:22.807583Z","caller":"traceutil/trace.go:171","msg":"trace[879378673] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:19758; }","duration":"250.038153ms","start":"2026-05-05T07:33:22.557502Z","end":"2026-05-05T07:33:22.80754Z","steps":["trace[879378673] 'agreement among raft nodes before linearized reading' (duration: 249.57116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:22.807453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.57931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-05T07:33:22.807673Z","caller":"traceutil/trace.go:171","msg":"trace[1529001836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19758; }","duration":"929.808596ms","start":"2026-05-05T07:33:21.877854Z","end":"2026-05-05T07:33:22.807662Z","steps":["trace[1529001836] 'agreement among raft nodes before linearized reading' (duration: 929.532198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:22.807704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:21.877846Z","time spent":"929.849987ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T07:33:22.80785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.725765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mpd5w\" ","response":"range_response_count:1 size:6532"} {"level":"info","ts":"2026-05-05T07:33:22.80788Z","caller":"traceutil/trace.go:171","msg":"trace[1202418318] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mpd5w; range_end:; response_count:1; response_revision:19758; }","duration":"892.756985ms","start":"2026-05-05T07:33:21.915115Z","end":"2026-05-05T07:33:22.807872Z","steps":["trace[1202418318] 'agreement among raft nodes before linearized reading' (duration: 892.673533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:33:22.807899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:33:21.915051Z","time spent":"892.842677ms","remote":"127.0.0.1:48812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-mpd5w\" "} {"level":"warn","ts":"2026-05-05T07:34:01.310724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920880724161274876,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T07:34:01.500712Z","caller":"traceutil/trace.go:171","msg":"trace[1706236853] transaction","detail":"{read_only:false; response_revision:19949; number_of_response:1; }","duration":"865.907483ms","start":"2026-05-05T07:34:00.634775Z","end":"2026-05-05T07:34:01.500683Z","steps":["trace[1706236853] 'process raft request' (duration: 865.751859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:01.500889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:00.634733Z","time spent":"866.058047ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T07:34:01.503259Z","caller":"traceutil/trace.go:171","msg":"trace[582286121] transaction","detail":"{read_only:false; response_revision:19950; number_of_response:1; }","duration":"569.063772ms","start":"2026-05-05T07:34:00.934157Z","end":"2026-05-05T07:34:01.503221Z","steps":["trace[582286121] 'process raft request' (duration: 568.591809ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:34:01.50339Z","caller":"traceutil/trace.go:171","msg":"trace[1814141748] transaction","detail":"{read_only:false; response_revision:19952; number_of_response:1; }","duration":"568.658331ms","start":"2026-05-05T07:34:00.934691Z","end":"2026-05-05T07:34:01.503349Z","steps":["trace[1814141748] 'process raft request' (duration: 568.381953ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:34:01.503411Z","caller":"traceutil/trace.go:171","msg":"trace[1689497311] transaction","detail":"{read_only:false; response_revision:19953; number_of_response:1; }","duration":"567.307173ms","start":"2026-05-05T07:34:00.936074Z","end":"2026-05-05T07:34:01.503382Z","steps":["trace[1689497311] 'process raft request' (duration: 567.059796ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:34:01.503436Z","caller":"traceutil/trace.go:171","msg":"trace[500563080] transaction","detail":"{read_only:false; response_revision:19951; number_of_response:1; }","duration":"569.154075ms","start":"2026-05-05T07:34:00.934262Z","end":"2026-05-05T07:34:01.503416Z","steps":["trace[500563080] 'process raft request' (duration: 568.733173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:01.503552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:00.934229Z","time spent":"569.256668ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:34:01.503562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:00.934668Z","time spent":"568.808975ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:34:01.503552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:00.936064Z","time spent":"567.418205ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:34:01.503428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:00.93411Z","time spent":"569.229406ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T07:34:01.503694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.159802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T07:34:01.503756Z","caller":"traceutil/trace.go:171","msg":"trace[24881155] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:19953; }","duration":"693.306126ms","start":"2026-05-05T07:34:00.810435Z","end":"2026-05-05T07:34:01.503741Z","steps":["trace[24881155] 'agreement among raft nodes before linearized reading' (duration: 693.048479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:01.503793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:00.810407Z","time spent":"693.377708ms","remote":"127.0.0.1:48970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":88,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-05T07:34:01.503263Z","caller":"traceutil/trace.go:171","msg":"trace[1561039175] linearizableReadLoop","detail":"{readStateIndex:20676; appliedIndex:20672; }","duration":"692.693608ms","start":"2026-05-05T07:34:00.810503Z","end":"2026-05-05T07:34:01.503197Z","steps":["trace[1561039175] 'read index received' (duration: 690.198058ms)","trace[1561039175] 'applied index is now lower than readState.Index' (duration: 2.49012ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T07:34:01.506864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.361587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-05T07:34:01.506949Z","caller":"traceutil/trace.go:171","msg":"trace[286183462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19953; }","duration":"516.4827ms","start":"2026-05-05T07:34:00.990452Z","end":"2026-05-05T07:34:01.506934Z","steps":["trace[286183462] 'agreement among raft nodes before linearized reading' (duration: 516.216673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:01.506999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:00.990439Z","time spent":"516.543003ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T07:34:01.507011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.13417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T07:34:01.507088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.634245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mpd5w\" ","response":"range_response_count:1 size:6532"} {"level":"info","ts":"2026-05-05T07:34:01.507123Z","caller":"traceutil/trace.go:171","msg":"trace[366182257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19953; }","duration":"162.261223ms","start":"2026-05-05T07:34:01.344813Z","end":"2026-05-05T07:34:01.507075Z","steps":["trace[366182257] 'agreement among raft nodes before linearized reading' (duration: 162.113989ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:34:01.507167Z","caller":"traceutil/trace.go:171","msg":"trace[501323061] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mpd5w; range_end:; response_count:1; response_revision:19953; }","duration":"393.754049ms","start":"2026-05-05T07:34:01.113376Z","end":"2026-05-05T07:34:01.50713Z","steps":["trace[501323061] 'agreement among raft nodes before linearized reading' (duration: 393.595814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:01.507228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:01.113359Z","time spent":"393.853142ms","remote":"127.0.0.1:48812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-mpd5w\" "} {"level":"warn","ts":"2026-05-05T07:34:01.507356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.16369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-05T07:34:01.507421Z","caller":"traceutil/trace.go:171","msg":"trace[1389544369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19953; }","duration":"511.214701ms","start":"2026-05-05T07:34:00.996171Z","end":"2026-05-05T07:34:01.507386Z","steps":["trace[1389544369] 'agreement among raft nodes before linearized reading' (duration: 511.083287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:01.50748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:00.996162Z","time spent":"511.308653ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T07:34:01.506922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.720719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-05T07:34:01.507659Z","caller":"traceutil/trace.go:171","msg":"trace[130461927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19953; }","duration":"510.525632ms","start":"2026-05-05T07:34:00.997117Z","end":"2026-05-05T07:34:01.507642Z","steps":["trace[130461927] 'agreement among raft nodes before linearized reading' (duration: 509.575565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:01.507709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:00.997102Z","time spent":"510.596044ms","remote":"127.0.0.1:48868","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-05T07:34:01.507694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.628785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-05T07:34:01.507865Z","caller":"traceutil/trace.go:171","msg":"trace[738258519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19953; }","duration":"516.708047ms","start":"2026-05-05T07:34:00.991035Z","end":"2026-05-05T07:34:01.507743Z","steps":["trace[738258519] 'agreement among raft nodes before linearized reading' (duration: 516.526602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:01.507891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.282724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T07:34:01.507939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:00.991029Z","time spent":"516.893423ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T07:34:01.508018Z","caller":"traceutil/trace.go:171","msg":"trace[1476021608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19953; }","duration":"517.50783ms","start":"2026-05-05T07:34:00.990464Z","end":"2026-05-05T07:34:01.507972Z","steps":["trace[1476021608] 'agreement among raft nodes before linearized reading' (duration: 517.080117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T07:34:01.508074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T07:34:00.990451Z","time spent":"517.612533ms","remote":"127.0.0.1:48868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T07:36:45.042181Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19252} {"level":"info","ts":"2026-05-05T07:36:45.105338Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19252,"took":"60.017992ms","hash":525335734,"current-db-size-bytes":57315328,"current-db-size":"57 MB","current-db-size-in-use-bytes":20537344,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:36:45.105424Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":525335734,"revision":19252,"compact-revision":17728} {"level":"info","ts":"2026-05-05T07:41:45.050086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20784} {"level":"info","ts":"2026-05-05T07:41:45.128258Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20784,"took":"75.498153ms","hash":783261578,"current-db-size-bytes":57315328,"current-db-size":"57 MB","current-db-size-in-use-bytes":20471808,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:41:45.128349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":783261578,"revision":20784,"compact-revision":19252} {"level":"info","ts":"2026-05-05T07:42:33.523376Z","caller":"traceutil/trace.go:171","msg":"trace[697539046] transaction","detail":"{read_only:false; response_revision:22552; number_of_response:1; }","duration":"123.187887ms","start":"2026-05-05T07:42:33.400162Z","end":"2026-05-05T07:42:33.52335Z","steps":["trace[697539046] 'process raft request' (duration: 123.124655ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:42:33.523608Z","caller":"traceutil/trace.go:171","msg":"trace[1622501637] transaction","detail":"{read_only:false; response_revision:22551; number_of_response:1; }","duration":"123.474315ms","start":"2026-05-05T07:42:33.400127Z","end":"2026-05-05T07:42:33.523602Z","steps":["trace[1622501637] 'process raft request' (duration: 122.683262ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T07:46:45.057166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22303} {"level":"info","ts":"2026-05-05T07:46:45.130963Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22303,"took":"70.420009ms","hash":102019274,"current-db-size-bytes":57315328,"current-db-size":"57 MB","current-db-size-in-use-bytes":20467712,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-05-05T07:46:45.131036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":102019274,"revision":22303,"compact-revision":20784} {"level":"info","ts":"2026-05-05T07:51:45.063728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23828} {"level":"info","ts":"2026-05-05T07:51:45.129791Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23828,"took":"63.252055ms","hash":4120085897,"current-db-size-bytes":57315328,"current-db-size":"57 MB","current-db-size-in-use-bytes":20656128,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-05T07:51:45.129874Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4120085897,"revision":23828,"compact-revision":22303}