{"level":"warn","ts":"2026-05-05T02:27:50.148631Z","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-05T02:27:50.148732Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.107:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.107:2380","--initial-cluster=instance=https://199.19.213.107:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.107:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.107: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-05T02:27:50.148805Z","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-05T02:27:50.148813Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.107:2380"]} {"level":"info","ts":"2026-05-05T02:27:50.148832Z","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-05T02:27:50.150315Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.107:2379"]} {"level":"info","ts":"2026-05-05T02:27:50.150395Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.107:2380"],"listen-peer-urls":["https://199.19.213.107:2380"],"advertise-client-urls":["https://199.19.213.107:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.107:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.107: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-05T02:27:50.155215Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.555085ms"} {"level":"info","ts":"2026-05-05T02:27:50.162603Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be75c9626aba3f77","cluster-id":"c32d1248c0d0c6d"} {"level":"info","ts":"2026-05-05T02:27:50.162734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T02:27:50.162768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became follower at term 0"} {"level":"info","ts":"2026-05-05T02:27:50.162787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be75c9626aba3f77 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T02:27:50.162802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became follower at term 1"} {"level":"info","ts":"2026-05-05T02:27:50.162861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 switched to configuration voters=(13724096864016023415)"} {"level":"warn","ts":"2026-05-05T02:27:50.169747Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T02:27:50.171628Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T02:27:50.172888Z","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-05T02:27:50.175175Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be75c9626aba3f77","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T02:27:50.175939Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be75c9626aba3f77","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T02:27:50.176096Z","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-05T02:27:50.176239Z","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-05T02:27:50.176261Z","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-05T02:27:50.180383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 switched to configuration voters=(13724096864016023415)"} {"level":"info","ts":"2026-05-05T02:27:50.180612Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c32d1248c0d0c6d","local-member-id":"be75c9626aba3f77","added-peer-id":"be75c9626aba3f77","added-peer-peer-urls":["https://199.19.213.107:2380"]} {"level":"info","ts":"2026-05-05T02:27:50.182462Z","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-05T02:27:50.182595Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.107:2380"} {"level":"info","ts":"2026-05-05T02:27:50.182647Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.107:2380"} {"level":"info","ts":"2026-05-05T02:27:50.182892Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be75c9626aba3f77","initial-advertise-peer-urls":["https://199.19.213.107:2380"],"listen-peer-urls":["https://199.19.213.107:2380"],"advertise-client-urls":["https://199.19.213.107:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.107:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T02:27:50.182945Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T02:27:50.764043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T02:27:50.764096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T02:27:50.764119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 received MsgPreVoteResp from be75c9626aba3f77 at term 1"} {"level":"info","ts":"2026-05-05T02:27:50.76413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became candidate at term 2"} {"level":"info","ts":"2026-05-05T02:27:50.764135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 received MsgVoteResp from be75c9626aba3f77 at term 2"} {"level":"info","ts":"2026-05-05T02:27:50.764143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be75c9626aba3f77 became leader at term 2"} {"level":"info","ts":"2026-05-05T02:27:50.76415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be75c9626aba3f77 elected leader be75c9626aba3f77 at term 2"} {"level":"info","ts":"2026-05-05T02:27:50.788256Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:27:50.790699Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c32d1248c0d0c6d","local-member-id":"be75c9626aba3f77","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:27:50.790797Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:27:50.790825Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:27:50.790859Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be75c9626aba3f77","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.107:2379]}","request-path":"/0/members/be75c9626aba3f77/attributes","cluster-id":"c32d1248c0d0c6d","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T02:27:50.790876Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T02:27:50.790895Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T02:27:50.791177Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T02:27:50.791245Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T02:27:50.792573Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T02:27:50.794121Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.107:2379"} {"level":"info","ts":"2026-05-05T02:28:56.72292Z","caller":"traceutil/trace.go:171","msg":"trace[187747174] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"218.857153ms","start":"2026-05-05T02:28:56.504027Z","end":"2026-05-05T02:28:56.722884Z","steps":["trace[187747174] 'process raft request' (duration: 218.293162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:28:57.293658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.737738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:28:57.293782Z","caller":"traceutil/trace.go:171","msg":"trace[404925527] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"477.740512ms","start":"2026-05-05T02:28:56.816029Z","end":"2026-05-05T02:28:57.293769Z","steps":["trace[404925527] 'read index received' (duration: 370.511604ms)","trace[404925527] 'applied index is now lower than readState.Index' (duration: 107.227978ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:28:57.293834Z","caller":"traceutil/trace.go:171","msg":"trace[262175443] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"519.603602ms","start":"2026-05-05T02:28:56.774221Z","end":"2026-05-05T02:28:57.293825Z","steps":["trace[262175443] 'process raft request' (duration: 412.307883ms)","trace[262175443] 'compare' (duration: 106.640577ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:28:57.293872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:28:56.774198Z","time spent":"519.654884ms","remote":"127.0.0.1:33456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T02:28:57.2942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.073559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T02:28:57.294295Z","caller":"traceutil/trace.go:171","msg":"trace[597729416] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:649; }","duration":"478.322903ms","start":"2026-05-05T02:28:56.815949Z","end":"2026-05-05T02:28:57.294272Z","steps":["trace[597729416] 'agreement among raft nodes before linearized reading' (duration: 477.897905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:28:57.294375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:28:56.815933Z","time spent":"478.426535ms","remote":"127.0.0.1:33026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":39,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-05T02:28:57.300347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.902492ms","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-05T02:28:57.300404Z","caller":"traceutil/trace.go:171","msg":"trace[1046450724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"364.007934ms","start":"2026-05-05T02:28:56.936382Z","end":"2026-05-05T02:28:57.30039Z","steps":["trace[1046450724] 'agreement among raft nodes before linearized reading' (duration: 363.855831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:28:57.300435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:28:56.936359Z","time spent":"364.068486ms","remote":"127.0.0.1:33184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T02:28:57.300468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.727464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8hwxt\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-05T02:28:57.300493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.707949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T02:28:57.300554Z","caller":"traceutil/trace.go:171","msg":"trace[54549547] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8hwxt; range_end:; response_count:1; response_revision:650; }","duration":"192.855987ms","start":"2026-05-05T02:28:57.107677Z","end":"2026-05-05T02:28:57.300533Z","steps":["trace[54549547] 'agreement among raft nodes before linearized reading' (duration: 192.592242ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:28:57.300583Z","caller":"traceutil/trace.go:171","msg":"trace[1911215011] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"292.831532ms","start":"2026-05-05T02:28:57.007726Z","end":"2026-05-05T02:28:57.300557Z","steps":["trace[1911215011] 'agreement among raft nodes before linearized reading' (duration: 292.564766ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:28:57.300498Z","caller":"traceutil/trace.go:171","msg":"trace[1063746553] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"110.191296ms","start":"2026-05-05T02:28:57.190248Z","end":"2026-05-05T02:28:57.300439Z","steps":["trace[1063746553] 'process raft request' (duration: 109.843589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:23.220202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.075329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T02:30:23.220296Z","caller":"traceutil/trace.go:171","msg":"trace[951494695] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1864; }","duration":"126.229262ms","start":"2026-05-05T02:30:23.094049Z","end":"2026-05-05T02:30:23.220279Z","steps":["trace[951494695] 'range keys from in-memory index tree' (duration: 125.833035ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:23.398393Z","caller":"traceutil/trace.go:171","msg":"trace[275034898] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"173.140315ms","start":"2026-05-05T02:30:23.225205Z","end":"2026-05-05T02:30:23.398346Z","steps":["trace[275034898] 'process raft request' (duration: 133.282175ms)","trace[275034898] 'compare' (duration: 39.685606ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:30:23.39856Z","caller":"traceutil/trace.go:171","msg":"trace[867587180] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"173.274318ms","start":"2026-05-05T02:30:23.225276Z","end":"2026-05-05T02:30:23.398551Z","steps":["trace[867587180] 'process raft request' (duration: 173.018833ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:01.859615Z","caller":"traceutil/trace.go:171","msg":"trace[506510367] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"273.872212ms","start":"2026-05-05T02:31:01.585717Z","end":"2026-05-05T02:31:01.859589Z","steps":["trace[506510367] 'process raft request' (duration: 273.717239ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:01.883914Z","caller":"traceutil/trace.go:171","msg":"trace[196837268] linearizableReadLoop","detail":"{readStateIndex:2209; appliedIndex:2207; }","duration":"218.196641ms","start":"2026-05-05T02:31:01.665691Z","end":"2026-05-05T02:31:01.883887Z","steps":["trace[196837268] 'read index received' (duration: 193.731028ms)","trace[196837268] 'applied index is now lower than readState.Index' (duration: 24.464503ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:01.884063Z","caller":"traceutil/trace.go:171","msg":"trace[343450615] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"297.350156ms","start":"2026-05-05T02:31:01.586605Z","end":"2026-05-05T02:31:01.883955Z","steps":["trace[343450615] 'process raft request' (duration: 297.118921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:01.884257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.505837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T02:31:01.884366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.643068ms","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-05T02:31:01.884398Z","caller":"traceutil/trace.go:171","msg":"trace[1240518005] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2128; }","duration":"218.713802ms","start":"2026-05-05T02:31:01.665652Z","end":"2026-05-05T02:31:01.884365Z","steps":["trace[1240518005] 'agreement among raft nodes before linearized reading' (duration: 218.401416ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:01.884423Z","caller":"traceutil/trace.go:171","msg":"trace[1094069743] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2128; }","duration":"185.7977ms","start":"2026-05-05T02:31:01.698606Z","end":"2026-05-05T02:31:01.884403Z","steps":["trace[1094069743] 'agreement among raft nodes before linearized reading' (duration: 185.589406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:14.199727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.218158ms","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-05T02:31:14.199827Z","caller":"traceutil/trace.go:171","msg":"trace[827599593] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2187; }","duration":"231.35493ms","start":"2026-05-05T02:31:13.968447Z","end":"2026-05-05T02:31:14.199802Z","steps":["trace[827599593] 'range keys from in-memory index tree' (duration: 231.022254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:14.199865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.843222ms","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-05T02:31:14.199926Z","caller":"traceutil/trace.go:171","msg":"trace[205999482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2187; }","duration":"228.932633ms","start":"2026-05-05T02:31:13.970977Z","end":"2026-05-05T02:31:14.199909Z","steps":["trace[205999482] 'range keys from in-memory index tree' (duration: 228.686929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:14.199967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.929746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T02:31:14.200083Z","caller":"traceutil/trace.go:171","msg":"trace[514961528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2187; }","duration":"217.089219ms","start":"2026-05-05T02:31:13.982972Z","end":"2026-05-05T02:31:14.200061Z","steps":["trace[514961528] 'range keys from in-memory index tree' (duration: 216.739562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:14.200186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.040815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T02:31:14.200264Z","caller":"traceutil/trace.go:171","msg":"trace[1425233355] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2187; }","duration":"316.172267ms","start":"2026-05-05T02:31:13.884076Z","end":"2026-05-05T02:31:14.200248Z","steps":["trace[1425233355] 'count revisions from in-memory index tree' (duration: 315.970364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:14.200321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:31:13.884055Z","time spent":"316.254759ms","remote":"127.0.0.1:33298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T02:31:14.200345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.542635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T02:31:14.200422Z","caller":"traceutil/trace.go:171","msg":"trace[1763612327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2187; }","duration":"274.660647ms","start":"2026-05-05T02:31:13.925742Z","end":"2026-05-05T02:31:14.200402Z","steps":["trace[1763612327] 'range keys from in-memory index tree' (duration: 274.364871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:31:14.201026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.41876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T02:31:14.201087Z","caller":"traceutil/trace.go:171","msg":"trace[1629693145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2187; }","duration":"285.522192ms","start":"2026-05-05T02:31:13.91555Z","end":"2026-05-05T02:31:14.201072Z","steps":["trace[1629693145] 'range keys from in-memory index tree' (duration: 284.476301ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:14.37885Z","caller":"traceutil/trace.go:171","msg":"trace[1720063367] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"174.387405ms","start":"2026-05-05T02:31:14.204441Z","end":"2026-05-05T02:31:14.378829Z","steps":["trace[1720063367] 'process raft request' (duration: 174.185331ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:14.379302Z","caller":"traceutil/trace.go:171","msg":"trace[958279963] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"174.709962ms","start":"2026-05-05T02:31:14.20457Z","end":"2026-05-05T02:31:14.37928Z","steps":["trace[958279963] 'process raft request' (duration: 174.221372ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:14.379428Z","caller":"traceutil/trace.go:171","msg":"trace[47746989] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"173.745673ms","start":"2026-05-05T02:31:14.205641Z","end":"2026-05-05T02:31:14.379387Z","steps":["trace[47746989] 'process raft request' (duration: 173.673661ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:14.379645Z","caller":"traceutil/trace.go:171","msg":"trace[178389046] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"174.833104ms","start":"2026-05-05T02:31:14.204768Z","end":"2026-05-05T02:31:14.379602Z","steps":["trace[178389046] 'process raft request' (duration: 174.355304ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:32:40.283811Z","caller":"traceutil/trace.go:171","msg":"trace[694033029] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"128.954791ms","start":"2026-05-05T02:32:40.154829Z","end":"2026-05-05T02:32:40.283784Z","steps":["trace[694033029] 'process raft request' (duration: 128.783438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:35:05.718653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.744678ms","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-05T02:35:05.7188Z","caller":"traceutil/trace.go:171","msg":"trace[332495965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3816; }","duration":"197.927353ms","start":"2026-05-05T02:35:05.520848Z","end":"2026-05-05T02:35:05.718775Z","steps":["trace[332495965] 'range keys from in-memory index tree' (duration: 197.511543ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:35:05.785586Z","caller":"traceutil/trace.go:171","msg":"trace[1613551216] linearizableReadLoop","detail":"{readStateIndex:3960; appliedIndex:3959; }","duration":"244.046913ms","start":"2026-05-05T02:35:05.541507Z","end":"2026-05-05T02:35:05.785554Z","steps":["trace[1613551216] 'read index received' (duration: 243.833789ms)","trace[1613551216] 'applied index is now lower than readState.Index' (duration: 211.574µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:35:05.785652Z","caller":"traceutil/trace.go:171","msg":"trace[622823781] transaction","detail":"{read_only:false; response_revision:3817; number_of_response:1; }","duration":"264.156435ms","start":"2026-05-05T02:35:05.521459Z","end":"2026-05-05T02:35:05.785615Z","steps":["trace[622823781] 'process raft request' (duration: 263.831238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:35:05.785858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.328529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T02:35:05.786079Z","caller":"traceutil/trace.go:171","msg":"trace[1975476060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3817; }","duration":"244.589733ms","start":"2026-05-05T02:35:05.54147Z","end":"2026-05-05T02:35:05.78606Z","steps":["trace[1975476060] 'agreement among raft nodes before linearized reading' (duration: 244.235926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:35:05.785847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.327698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T02:35:05.786147Z","caller":"traceutil/trace.go:171","msg":"trace[414304128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3817; }","duration":"244.686156ms","start":"2026-05-05T02:35:05.541441Z","end":"2026-05-05T02:35:05.786127Z","steps":["trace[414304128] 'agreement among raft nodes before linearized reading' (duration: 244.257097ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:35:05.799174Z","caller":"traceutil/trace.go:171","msg":"trace[770820170] transaction","detail":"{read_only:false; response_revision:3818; number_of_response:1; }","duration":"138.474556ms","start":"2026-05-05T02:35:05.660677Z","end":"2026-05-05T02:35:05.799152Z","steps":["trace[770820170] 'process raft request' (duration: 138.314973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:35:05.799201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.062325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:35:05.799302Z","caller":"traceutil/trace.go:171","msg":"trace[1973264508] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:3819; }","duration":"209.199968ms","start":"2026-05-05T02:35:05.590088Z","end":"2026-05-05T02:35:05.799288Z","steps":["trace[1973264508] 'agreement among raft nodes before linearized reading' (duration: 209.044155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:36:26.764185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.082943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14497"} {"level":"info","ts":"2026-05-05T02:36:26.764279Z","caller":"traceutil/trace.go:171","msg":"trace[805296630] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4830; }","duration":"115.227066ms","start":"2026-05-05T02:36:26.649032Z","end":"2026-05-05T02:36:26.764259Z","steps":["trace[805296630] 'agreement among raft nodes before linearized reading' (duration: 114.977811ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:36:26.90105Z","caller":"traceutil/trace.go:171","msg":"trace[2056861372] linearizableReadLoop","detail":"{readStateIndex:5018; appliedIndex:5016; }","duration":"104.250377ms","start":"2026-05-05T02:36:26.796778Z","end":"2026-05-05T02:36:26.901028Z","steps":["trace[2056861372] 'read index received' (duration: 68.763326ms)","trace[2056861372] 'applied index is now lower than readState.Index' (duration: 35.486141ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:36:26.9013Z","caller":"traceutil/trace.go:171","msg":"trace[1127011128] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"123.298227ms","start":"2026-05-05T02:36:26.777991Z","end":"2026-05-05T02:36:26.901289Z","steps":["trace[1127011128] 'process raft request' (duration: 122.97419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:36:26.901553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.723426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-b631cb13-f4c4-437e-aa4b-c03a47e72803\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-05-05T02:36:26.901585Z","caller":"traceutil/trace.go:171","msg":"trace[1492528236] range","detail":"{range_begin:/registry/persistentvolumes/pvc-b631cb13-f4c4-437e-aa4b-c03a47e72803; range_end:; response_count:1; response_revision:4833; }","duration":"131.803557ms","start":"2026-05-05T02:36:26.769774Z","end":"2026-05-05T02:36:26.901577Z","steps":["trace[1492528236] 'agreement among raft nodes before linearized reading' (duration: 131.682505ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:36:26.901742Z","caller":"traceutil/trace.go:171","msg":"trace[1790542935] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"124.896299ms","start":"2026-05-05T02:36:26.776813Z","end":"2026-05-05T02:36:26.901709Z","steps":["trace[1790542935] 'process raft request' (duration: 88.716815ms)","trace[1790542935] 'compare' (duration: 35.260966ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:36:26.90805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.639134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-05-05T02:36:26.909157Z","caller":"traceutil/trace.go:171","msg":"trace[1043628209] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4834; }","duration":"103.079442ms","start":"2026-05-05T02:36:26.806059Z","end":"2026-05-05T02:36:26.909138Z","steps":["trace[1043628209] 'agreement among raft nodes before linearized reading' (duration: 99.088282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:36:32.195625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.783027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-05T02:36:32.19571Z","caller":"traceutil/trace.go:171","msg":"trace[446880640] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5015; }","duration":"147.951541ms","start":"2026-05-05T02:36:32.047742Z","end":"2026-05-05T02:36:32.195694Z","steps":["trace[446880640] 'range keys from in-memory index tree' (duration: 147.565083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:36:32.195878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.465371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5618cc2f-ebf7-4175-9258-8c3a5930ab02\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-05T02:36:32.195913Z","caller":"traceutil/trace.go:171","msg":"trace[595318110] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5618cc2f-ebf7-4175-9258-8c3a5930ab02; range_end:; response_count:1; response_revision:5015; }","duration":"160.538223ms","start":"2026-05-05T02:36:32.035365Z","end":"2026-05-05T02:36:32.195904Z","steps":["trace[595318110] 'range keys from in-memory index tree' (duration: 160.256936ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:36:32.217477Z","caller":"traceutil/trace.go:171","msg":"trace[1204922604] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"127.184284ms","start":"2026-05-05T02:36:32.090253Z","end":"2026-05-05T02:36:32.217437Z","steps":["trace[1204922604] 'process raft request' (duration: 126.937079ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:36:32.556521Z","caller":"traceutil/trace.go:171","msg":"trace[2262906] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"114.44875ms","start":"2026-05-05T02:36:32.442044Z","end":"2026-05-05T02:36:32.556493Z","steps":["trace[2262906] 'process raft request' (duration: 114.275986ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:36:32.593976Z","caller":"traceutil/trace.go:171","msg":"trace[1879072225] linearizableReadLoop","detail":"{readStateIndex:5213; appliedIndex:5211; }","duration":"146.956051ms","start":"2026-05-05T02:36:32.446994Z","end":"2026-05-05T02:36:32.59395Z","steps":["trace[1879072225] 'read index received' (duration: 109.331487ms)","trace[1879072225] 'applied index is now lower than readState.Index' (duration: 37.623204ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:36:32.594027Z","caller":"traceutil/trace.go:171","msg":"trace[2053211743] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"149.800229ms","start":"2026-05-05T02:36:32.444192Z","end":"2026-05-05T02:36:32.593992Z","steps":["trace[2053211743] 'process raft request' (duration: 149.614445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:36:32.594192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.167866ms","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:7"} {"level":"info","ts":"2026-05-05T02:36:32.594256Z","caller":"traceutil/trace.go:171","msg":"trace[342559079] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5022; }","duration":"147.276587ms","start":"2026-05-05T02:36:32.446965Z","end":"2026-05-05T02:36:32.594242Z","steps":["trace[342559079] 'agreement among raft nodes before linearized reading' (duration: 147.105264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:36:42.714398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.906489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:36:42.714581Z","caller":"traceutil/trace.go:171","msg":"trace[1667378753] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"154.8624ms","start":"2026-05-05T02:36:42.559659Z","end":"2026-05-05T02:36:42.714522Z","steps":["trace[1667378753] 'process raft request' (duration: 29.575213ms)","trace[1667378753] 'compare' (duration: 124.727235ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:36:52.173149Z","caller":"traceutil/trace.go:171","msg":"trace[34616859] transaction","detail":"{read_only:false; response_revision:5442; number_of_response:1; }","duration":"124.380109ms","start":"2026-05-05T02:36:52.048747Z","end":"2026-05-05T02:36:52.173128Z","steps":["trace[34616859] 'process raft request' (duration: 124.231746ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:36:52.357679Z","caller":"traceutil/trace.go:171","msg":"trace[656975814] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"114.098193ms","start":"2026-05-05T02:36:52.243543Z","end":"2026-05-05T02:36:52.357641Z","steps":["trace[656975814] 'process raft request' (duration: 17.082121ms)","trace[656975814] 'compare' (duration: 96.819518ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:37:50.979562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2909} {"level":"info","ts":"2026-05-05T02:37:51.050946Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2909,"took":"69.220889ms","hash":3801352653,"current-db-size-bytes":56094720,"current-db-size":"56 MB","current-db-size-in-use-bytes":33173504,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-05T02:37:51.051002Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3801352653,"revision":2909,"compact-revision":-1} {"level":"info","ts":"2026-05-05T02:41:15.120722Z","caller":"traceutil/trace.go:171","msg":"trace[1729462212] transaction","detail":"{read_only:false; response_revision:7378; number_of_response:1; }","duration":"181.964394ms","start":"2026-05-05T02:41:14.938716Z","end":"2026-05-05T02:41:15.12068Z","steps":["trace[1729462212] 'process raft request' (duration: 181.714899ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:41:15.179919Z","caller":"traceutil/trace.go:171","msg":"trace[1017349210] transaction","detail":"{read_only:false; response_revision:7379; number_of_response:1; }","duration":"128.817698ms","start":"2026-05-05T02:41:15.051046Z","end":"2026-05-05T02:41:15.179864Z","steps":["trace[1017349210] 'process raft request' (duration: 128.604393ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:42:00.592253Z","caller":"traceutil/trace.go:171","msg":"trace[2117598548] transaction","detail":"{read_only:false; response_revision:7693; number_of_response:1; }","duration":"108.638829ms","start":"2026-05-05T02:42:00.483582Z","end":"2026-05-05T02:42:00.592221Z","steps":["trace[2117598548] 'process raft request' (duration: 108.430954ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:42:50.987028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5972} {"level":"info","ts":"2026-05-05T02:42:51.087507Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5972,"took":"97.89742ms","hash":2871738951,"current-db-size-bytes":56094720,"current-db-size":"56 MB","current-db-size-in-use-bytes":21131264,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-05T02:42:51.087592Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2871738951,"revision":5972,"compact-revision":2909} {"level":"warn","ts":"2026-05-05T02:43:22.011306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.622111ms","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-05T02:43:22.011411Z","caller":"traceutil/trace.go:171","msg":"trace[21923135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8353; }","duration":"135.784625ms","start":"2026-05-05T02:43:21.875608Z","end":"2026-05-05T02:43:22.011392Z","steps":["trace[21923135] 'range keys from in-memory index tree' (duration: 135.432018ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:43:51.111918Z","caller":"traceutil/trace.go:171","msg":"trace[779319956] linearizableReadLoop","detail":"{readStateIndex:8868; appliedIndex:8867; }","duration":"376.702758ms","start":"2026-05-05T02:43:50.735181Z","end":"2026-05-05T02:43:51.111884Z","steps":["trace[779319956] 'read index received' (duration: 376.558875ms)","trace[779319956] 'applied index is now lower than readState.Index' (duration: 142.693µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:43:51.112069Z","caller":"traceutil/trace.go:171","msg":"trace[1363727417] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"379.075876ms","start":"2026-05-05T02:43:50.732957Z","end":"2026-05-05T02:43:51.112033Z","steps":["trace[1363727417] 'process raft request' (duration: 378.744699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:43:51.112256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:43:50.732938Z","time spent":"379.215698ms","remote":"127.0.0.1:33184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:43:51.112284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.070266ms","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-05T02:43:51.112335Z","caller":"traceutil/trace.go:171","msg":"trace[378182772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8561; }","duration":"377.164828ms","start":"2026-05-05T02:43:50.735158Z","end":"2026-05-05T02:43:51.112322Z","steps":["trace[378182772] 'agreement among raft nodes before linearized reading' (duration: 376.957313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:43:51.112376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:43:50.735148Z","time spent":"377.219668ms","remote":"127.0.0.1:33184","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-05T02:43:51.112621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.664548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T02:43:51.112658Z","caller":"traceutil/trace.go:171","msg":"trace[402995969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8561; }","duration":"371.71535ms","start":"2026-05-05T02:43:50.740932Z","end":"2026-05-05T02:43:51.112647Z","steps":["trace[402995969] 'agreement among raft nodes before linearized reading' (duration: 371.626668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:43:51.112685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:43:50.740923Z","time spent":"371.75515ms","remote":"127.0.0.1:33184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T02:43:51.112942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.277947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T02:43:51.11298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.296975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T02:43:51.112996Z","caller":"traceutil/trace.go:171","msg":"trace[1139077487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8561; }","duration":"332.35035ms","start":"2026-05-05T02:43:50.780633Z","end":"2026-05-05T02:43:51.112984Z","steps":["trace[1139077487] 'agreement among raft nodes before linearized reading' (duration: 332.157986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:43:51.113031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:43:50.780625Z","time spent":"332.399961ms","remote":"127.0.0.1:33184","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":"info","ts":"2026-05-05T02:43:51.113049Z","caller":"traceutil/trace.go:171","msg":"trace[344902095] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8561; }","duration":"347.359367ms","start":"2026-05-05T02:43:50.765647Z","end":"2026-05-05T02:43:51.113006Z","steps":["trace[344902095] 'agreement among raft nodes before linearized reading' (duration: 347.290294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:43:51.11309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:43:50.765635Z","time spent":"347.446159ms","remote":"127.0.0.1:33180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-05T02:43:51.113165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.330892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-05T02:43:51.113463Z","caller":"traceutil/trace.go:171","msg":"trace[495739556] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8561; }","duration":"262.539885ms","start":"2026-05-05T02:43:50.850754Z","end":"2026-05-05T02:43:51.113294Z","steps":["trace[495739556] 'agreement among raft nodes before linearized reading' (duration: 262.152517ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:43:55.399901Z","caller":"traceutil/trace.go:171","msg":"trace[583001788] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"264.22929ms","start":"2026-05-05T02:43:55.135638Z","end":"2026-05-05T02:43:55.399867Z","steps":["trace[583001788] 'process raft request' (duration: 263.972445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:43:55.400219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.112027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T02:43:55.400327Z","caller":"traceutil/trace.go:171","msg":"trace[539198825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8581; }","duration":"202.291ms","start":"2026-05-05T02:43:55.198013Z","end":"2026-05-05T02:43:55.400304Z","steps":["trace[539198825] 'agreement among raft nodes before linearized reading' (duration: 201.987245ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:43:55.399843Z","caller":"traceutil/trace.go:171","msg":"trace[67010367] linearizableReadLoop","detail":"{readStateIndex:8889; appliedIndex:8888; }","duration":"201.72193ms","start":"2026-05-05T02:43:55.19809Z","end":"2026-05-05T02:43:55.399812Z","steps":["trace[67010367] 'read index received' (duration: 201.508506ms)","trace[67010367] 'applied index is now lower than readState.Index' (duration: 211.614µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:43:55.400717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.842094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T02:43:55.400763Z","caller":"traceutil/trace.go:171","msg":"trace[1161339076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8581; }","duration":"195.957705ms","start":"2026-05-05T02:43:55.204793Z","end":"2026-05-05T02:43:55.400751Z","steps":["trace[1161339076] 'agreement among raft nodes before linearized reading' (duration: 195.760621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:43:55.400797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.820703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T02:43:55.400876Z","caller":"traceutil/trace.go:171","msg":"trace[1491068288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8581; }","duration":"195.988396ms","start":"2026-05-05T02:43:55.204873Z","end":"2026-05-05T02:43:55.400861Z","steps":["trace[1491068288] 'agreement among raft nodes before linearized reading' (duration: 195.717741ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:43:55.512776Z","caller":"traceutil/trace.go:171","msg":"trace[859336527] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"106.90889ms","start":"2026-05-05T02:43:55.405837Z","end":"2026-05-05T02:43:55.512746Z","steps":["trace[859336527] 'process raft request' (duration: 106.858239ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:43:55.512818Z","caller":"traceutil/trace.go:171","msg":"trace[473281758] transaction","detail":"{read_only:false; response_revision:8583; number_of_response:1; }","duration":"107.124474ms","start":"2026-05-05T02:43:55.405657Z","end":"2026-05-05T02:43:55.512782Z","steps":["trace[473281758] 'process raft request' (duration: 106.90229ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:44:03.643862Z","caller":"traceutil/trace.go:171","msg":"trace[113513636] transaction","detail":"{read_only:false; response_revision:8669; number_of_response:1; }","duration":"150.28296ms","start":"2026-05-05T02:44:03.493517Z","end":"2026-05-05T02:44:03.6438Z","steps":["trace[113513636] 'process raft request' (duration: 54.564481ms)","trace[113513636] 'compare' (duration: 95.452164ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:44:03.815785Z","caller":"traceutil/trace.go:171","msg":"trace[904779844] transaction","detail":"{read_only:false; response_revision:8672; number_of_response:1; }","duration":"165.395009ms","start":"2026-05-05T02:44:03.65035Z","end":"2026-05-05T02:44:03.815745Z","steps":["trace[904779844] 'process raft request' (duration: 149.485233ms)","trace[904779844] 'compare' (duration: 15.678531ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:44:03.91529Z","caller":"traceutil/trace.go:171","msg":"trace[1703331462] linearizableReadLoop","detail":"{readStateIndex:8986; appliedIndex:8983; }","duration":"162.262127ms","start":"2026-05-05T02:44:03.752986Z","end":"2026-05-05T02:44:03.915248Z","steps":["trace[1703331462] 'read index received' (duration: 46.9337ms)","trace[1703331462] 'applied index is now lower than readState.Index' (duration: 115.325447ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:44:03.915374Z","caller":"traceutil/trace.go:171","msg":"trace[1587804421] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"257.336502ms","start":"2026-05-05T02:44:03.657968Z","end":"2026-05-05T02:44:03.915305Z","steps":["trace[1587804421] 'process raft request' (duration: 257.072676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:44:03.915464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.452481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:44:03.915144Z","caller":"traceutil/trace.go:171","msg":"trace[139832039] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"264.571735ms","start":"2026-05-05T02:44:03.650517Z","end":"2026-05-05T02:44:03.915088Z","steps":["trace[139832039] 'process raft request' (duration: 264.290409ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:44:03.915556Z","caller":"traceutil/trace.go:171","msg":"trace[173571132] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8674; }","duration":"162.617524ms","start":"2026-05-05T02:44:03.752917Z","end":"2026-05-05T02:44:03.915534Z","steps":["trace[173571132] 'agreement among raft nodes before linearized reading' (duration: 162.455821ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:44:57.292898Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000250d.wal"} {"level":"info","ts":"2026-05-05T02:45:54.560953Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be75c9626aba3f77","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T02:45:54.566918Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T02:45:54.56705Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T02:47:50.99348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8019} {"level":"info","ts":"2026-05-05T02:47:51.05427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8019,"took":"58.875238ms","hash":368293852,"current-db-size-bytes":56094720,"current-db-size":"56 MB","current-db-size-in-use-bytes":28073984,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T02:47:51.054336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":368293852,"revision":8019,"compact-revision":5972} {"level":"warn","ts":"2026-05-05T02:51:00.459228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.687211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:51:00.459444Z","caller":"traceutil/trace.go:171","msg":"trace[314121085] linearizableReadLoop","detail":"{readStateIndex:12275; appliedIndex:12273; }","duration":"257.952818ms","start":"2026-05-05T02:51:00.201465Z","end":"2026-05-05T02:51:00.459418Z","steps":["trace[314121085] 'read index received' (duration: 11.648884ms)","trace[314121085] 'applied index is now lower than readState.Index' (duration: 246.277723ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:51:00.459584Z","caller":"traceutil/trace.go:171","msg":"trace[671337109] transaction","detail":"{read_only:false; response_revision:11861; number_of_response:1; }","duration":"259.229284ms","start":"2026-05-05T02:51:00.200337Z","end":"2026-05-05T02:51:00.459566Z","steps":["trace[671337109] 'process raft request' (duration: 78.071423ms)","trace[671337109] 'compare' (duration: 180.534058ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:51:00.460138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.671282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-05T02:51:00.46019Z","caller":"traceutil/trace.go:171","msg":"trace[868465669] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11861; }","duration":"258.758054ms","start":"2026-05-05T02:51:00.201415Z","end":"2026-05-05T02:51:00.460173Z","steps":["trace[868465669] 'agreement among raft nodes before linearized reading' (duration: 258.452618ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:51:01.612914Z","caller":"traceutil/trace.go:171","msg":"trace[359216843] transaction","detail":"{read_only:false; response_revision:11869; number_of_response:1; }","duration":"145.248059ms","start":"2026-05-05T02:51:01.467622Z","end":"2026-05-05T02:51:01.61287Z","steps":["trace[359216843] 'process raft request' (duration: 116.855298ms)","trace[359216843] 'compare' (duration: 27.856339ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:51:46.146697Z","caller":"traceutil/trace.go:171","msg":"trace[902810689] transaction","detail":"{read_only:false; response_revision:12189; number_of_response:1; }","duration":"115.228735ms","start":"2026-05-05T02:51:46.031437Z","end":"2026-05-05T02:51:46.146666Z","steps":["trace[902810689] 'process raft request' (duration: 114.727635ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:52:51.004595Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10416} {"level":"info","ts":"2026-05-05T02:52:51.091889Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10416,"took":"84.288443ms","hash":2354472690,"current-db-size-bytes":56094720,"current-db-size":"56 MB","current-db-size-in-use-bytes":26472448,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T02:52:51.091951Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2354472690,"revision":10416,"compact-revision":8019} {"level":"info","ts":"2026-05-05T02:57:01.562304Z","caller":"traceutil/trace.go:171","msg":"trace[1693255644] transaction","detail":"{read_only:false; response_revision:14308; number_of_response:1; }","duration":"124.710701ms","start":"2026-05-05T02:57:01.437561Z","end":"2026-05-05T02:57:01.562272Z","steps":["trace[1693255644] 'process raft request' (duration: 124.520227ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:57:01.575221Z","caller":"traceutil/trace.go:171","msg":"trace[1292492443] transaction","detail":"{read_only:false; response_revision:14309; number_of_response:1; }","duration":"103.925254ms","start":"2026-05-05T02:57:01.471259Z","end":"2026-05-05T02:57:01.575185Z","steps":["trace[1292492443] 'process raft request' (duration: 103.70157ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:57:51.015977Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12611} {"level":"info","ts":"2026-05-05T02:57:51.10213Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12611,"took":"83.755478ms","hash":4152032326,"current-db-size-bytes":56094720,"current-db-size":"56 MB","current-db-size-in-use-bytes":26656768,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-05T02:57:51.102207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4152032326,"revision":12611,"compact-revision":10416} {"level":"info","ts":"2026-05-05T02:58:20.320252Z","caller":"traceutil/trace.go:171","msg":"trace[1566375036] linearizableReadLoop","detail":"{readStateIndex:15233; appliedIndex:15232; }","duration":"314.16865ms","start":"2026-05-05T02:58:20.006048Z","end":"2026-05-05T02:58:20.320217Z","steps":["trace[1566375036] 'read index received' (duration: 314.129869ms)","trace[1566375036] 'applied index is now lower than readState.Index' (duration: 37.36µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:58:20.320395Z","caller":"traceutil/trace.go:171","msg":"trace[1699771890] transaction","detail":"{read_only:false; response_revision:14716; number_of_response:1; }","duration":"441.468482ms","start":"2026-05-05T02:58:19.87887Z","end":"2026-05-05T02:58:20.320338Z","steps":["trace[1699771890] 'process raft request' (duration: 441.195737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:58:20.320543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.467415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-05-05T02:58:20.320613Z","caller":"traceutil/trace.go:171","msg":"trace[1902361832] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14716; }","duration":"314.621519ms","start":"2026-05-05T02:58:20.00597Z","end":"2026-05-05T02:58:20.320592Z","steps":["trace[1902361832] 'agreement among raft nodes before linearized reading' (duration: 314.395184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:58:20.320672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:58:20.005943Z","time spent":"314.716141ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-05T02:58:20.320707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:58:19.878833Z","time spent":"441.710817ms","remote":"127.0.0.1:33184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T02:58:20.321521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.126883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-05T02:58:20.321613Z","caller":"traceutil/trace.go:171","msg":"trace[2122854612] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14715; }","duration":"599.239496ms","start":"2026-05-05T02:58:19.722353Z","end":"2026-05-05T02:58:20.321592Z","steps":["trace[2122854612] 'range keys from in-memory index tree' (duration: 597.238405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:58:20.321685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:58:19.722289Z","time spent":"599.367938ms","remote":"127.0.0.1:33134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-05T02:58:20.321847Z","caller":"traceutil/trace.go:171","msg":"trace[1725878441] transaction","detail":"{read_only:false; response_revision:14717; number_of_response:1; }","duration":"176.236414ms","start":"2026-05-05T02:58:20.145552Z","end":"2026-05-05T02:58:20.321789Z","steps":["trace[1725878441] 'process raft request' (duration: 175.908318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:58:20.321953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.324902ms","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-05T02:58:20.322066Z","caller":"traceutil/trace.go:171","msg":"trace[349318085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14717; }","duration":"297.497845ms","start":"2026-05-05T02:58:20.024538Z","end":"2026-05-05T02:58:20.322036Z","steps":["trace[349318085] 'agreement among raft nodes before linearized reading' (duration: 297.151559ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:58:47.128202Z","caller":"traceutil/trace.go:171","msg":"trace[1284780806] transaction","detail":"{read_only:false; response_revision:14852; number_of_response:1; }","duration":"115.341702ms","start":"2026-05-05T02:58:47.012819Z","end":"2026-05-05T02:58:47.128161Z","steps":["trace[1284780806] 'process raft request' (duration: 113.018785ms)"],"step_count":1}