{"level":"warn","ts":"2026-03-26T14:04:01.023619Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-26T14:04:01.023706Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-03-26T14:04:01.023775Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-26T14:04:01.023831Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-03-26T14:04:01.023851Z","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-03-26T14:04:01.024556Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-03-26T14:04:01.024656Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-03-26T14:04:01.027997Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.049889ms"} {"level":"info","ts":"2026-03-26T14:04:01.033792Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-03-26T14:04:01.033879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-03-26T14:04:01.033905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-03-26T14:04:01.033924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-26T14:04:01.033941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-03-26T14:04:01.033978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-03-26T14:04:01.0416Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-26T14:04:01.042678Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-26T14:04:01.043829Z","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-03-26T14:04:01.04572Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-26T14:04:01.046699Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-26T14:04:01.046966Z","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-03-26T14:04:01.047042Z","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-03-26T14:04:01.047055Z","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-03-26T14:04:01.048236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-03-26T14:04:01.04854Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-03-26T14:04:01.051313Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-03-26T14:04:01.051383Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-03-26T14:04:01.050961Z","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-03-26T14:04:01.051917Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-26T14:04:01.05201Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-26T14:04:01.234329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-03-26T14:04:01.234394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-26T14:04:01.234412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-03-26T14:04:01.234427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-03-26T14:04:01.234437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-03-26T14:04:01.23445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-03-26T14:04:01.23446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-03-26T14:04:01.236222Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T14:04:01.237049Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T14:04:01.237043Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-03-26T14:04:01.237168Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T14:04:01.237265Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T14:04:01.237504Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-26T14:04:01.237881Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T14:04:01.237905Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-26T14:04:01.237934Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T14:04:01.240828Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-03-26T14:04:01.241194Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-26T14:04:12.041527Z","caller":"traceutil/trace.go:171","msg":"trace[1648655373] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"108.331369ms","start":"2026-03-26T14:04:11.933169Z","end":"2026-03-26T14:04:12.0415Z","steps":["trace[1648655373] 'process raft request' (duration: 107.701715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:04:17.674745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.038286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T14:04:17.674891Z","caller":"traceutil/trace.go:171","msg":"trace[1708637766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"192.296392ms","start":"2026-03-26T14:04:17.482572Z","end":"2026-03-26T14:04:17.674868Z","steps":["trace[1708637766] 'range keys from in-memory index tree' (duration: 191.875982ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:04:17.848135Z","caller":"traceutil/trace.go:171","msg":"trace[2095908004] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"169.664143ms","start":"2026-03-26T14:04:17.678435Z","end":"2026-03-26T14:04:17.848099Z","steps":["trace[2095908004] 'process raft request' (duration: 169.49635ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:04:33.280633Z","caller":"traceutil/trace.go:171","msg":"trace[786679272] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"208.063872ms","start":"2026-03-26T14:04:33.072532Z","end":"2026-03-26T14:04:33.280596Z","steps":["trace[786679272] 'process raft request' (duration: 178.420383ms)","trace[786679272] 'compare' (duration: 28.916133ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:04:33.281479Z","caller":"traceutil/trace.go:171","msg":"trace[438391107] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"184.179736ms","start":"2026-03-26T14:04:33.097285Z","end":"2026-03-26T14:04:33.281465Z","steps":["trace[438391107] 'process raft request' (duration: 183.995182ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:04:33.281677Z","caller":"traceutil/trace.go:171","msg":"trace[1784320996] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"191.314639ms","start":"2026-03-26T14:04:33.090352Z","end":"2026-03-26T14:04:33.281666Z","steps":["trace[1784320996] 'process raft request' (duration: 190.584893ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:04:33.557398Z","caller":"traceutil/trace.go:171","msg":"trace[314427603] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"115.231778ms","start":"2026-03-26T14:04:33.442142Z","end":"2026-03-26T14:04:33.557374Z","steps":["trace[314427603] 'process raft request' (duration: 114.331807ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:04:35.554506Z","caller":"traceutil/trace.go:171","msg":"trace[515989244] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"173.026381ms","start":"2026-03-26T14:04:35.381447Z","end":"2026-03-26T14:04:35.554473Z","steps":["trace[515989244] 'process raft request' (duration: 172.825487ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:04:40.760999Z","caller":"traceutil/trace.go:171","msg":"trace[443659235] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"300.358286ms","start":"2026-03-26T14:04:40.460609Z","end":"2026-03-26T14:04:40.760967Z","steps":["trace[443659235] 'read index received' (duration: 300.09902ms)","trace[443659235] 'applied index is now lower than readState.Index' (duration: 258.366µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:04:40.761072Z","caller":"traceutil/trace.go:171","msg":"trace[813460598] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"408.346397ms","start":"2026-03-26T14:04:40.35268Z","end":"2026-03-26T14:04:40.761027Z","steps":["trace[813460598] 'process raft request' (duration: 408.083061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:04:40.761427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.805996ms","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-03-26T14:04:40.761518Z","caller":"traceutil/trace.go:171","msg":"trace[400452720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"300.95548ms","start":"2026-03-26T14:04:40.460539Z","end":"2026-03-26T14:04:40.761495Z","steps":["trace[400452720] 'agreement among raft nodes before linearized reading' (duration: 300.5796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:04:40.761577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T14:04:40.460496Z","time spent":"301.068062ms","remote":"127.0.0.1:59426","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-03-26T14:04:40.761845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T14:04:40.352652Z","time spent":"408.519271ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T14:04:41.041118Z","caller":"traceutil/trace.go:171","msg":"trace[345123342] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"233.72351ms","start":"2026-03-26T14:04:40.807369Z","end":"2026-03-26T14:04:41.041093Z","steps":["trace[345123342] 'read index received' (duration: 227.131669ms)","trace[345123342] 'applied index is now lower than readState.Index' (duration: 6.590761ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:04:41.041163Z","caller":"traceutil/trace.go:171","msg":"trace[1656393057] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"263.303777ms","start":"2026-03-26T14:04:40.777821Z","end":"2026-03-26T14:04:41.041125Z","steps":["trace[1656393057] 'process raft request' (duration: 256.818419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:04:41.041324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.940934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-26T14:04:41.041371Z","caller":"traceutil/trace.go:171","msg":"trace[1834562263] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:624; }","duration":"234.049097ms","start":"2026-03-26T14:04:40.80731Z","end":"2026-03-26T14:04:41.041359Z","steps":["trace[1834562263] 'agreement among raft nodes before linearized reading' (duration: 233.928034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:04:41.041696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.002089ms","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-03-26T14:04:41.041853Z","caller":"traceutil/trace.go:171","msg":"trace[1476893211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"221.205704ms","start":"2026-03-26T14:04:40.820615Z","end":"2026-03-26T14:04:41.041821Z","steps":["trace[1476893211] 'agreement among raft nodes before linearized reading' (duration: 220.830545ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:04:57.092187Z","caller":"traceutil/trace.go:171","msg":"trace[1456903257] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"196.849086ms","start":"2026-03-26T14:04:56.895313Z","end":"2026-03-26T14:04:57.092162Z","steps":["trace[1456903257] 'read index received' (duration: 196.605251ms)","trace[1456903257] 'applied index is now lower than readState.Index' (duration: 242.385µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:04:57.092293Z","caller":"traceutil/trace.go:171","msg":"trace[637825583] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"274.393871ms","start":"2026-03-26T14:04:56.817869Z","end":"2026-03-26T14:04:57.092263Z","steps":["trace[637825583] 'process raft request' (duration: 274.119115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:04:57.092425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.088242ms","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-03-26T14:04:57.092463Z","caller":"traceutil/trace.go:171","msg":"trace[1851775762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:786; }","duration":"197.197625ms","start":"2026-03-26T14:04:56.895256Z","end":"2026-03-26T14:04:57.092454Z","steps":["trace[1851775762] 'agreement among raft nodes before linearized reading' (duration: 197.024621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:05:26.396082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.50806ms","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-03-26T14:05:26.396167Z","caller":"traceutil/trace.go:171","msg":"trace[1971991524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1182; }","duration":"101.633933ms","start":"2026-03-26T14:05:26.294505Z","end":"2026-03-26T14:05:26.396139Z","steps":["trace[1971991524] 'range keys from in-memory index tree' (duration: 101.356687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:06:18.587109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.534526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T14:06:18.587209Z","caller":"traceutil/trace.go:171","msg":"trace[498429922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1876; }","duration":"103.6981ms","start":"2026-03-26T14:06:18.483492Z","end":"2026-03-26T14:06:18.58719Z","steps":["trace[498429922] 'range keys from in-memory index tree' (duration: 103.446065ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:06:18.72274Z","caller":"traceutil/trace.go:171","msg":"trace[1828109397] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"226.716419ms","start":"2026-03-26T14:06:18.496007Z","end":"2026-03-26T14:06:18.722723Z","steps":["trace[1828109397] 'process raft request' (duration: 226.507133ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:06:18.722718Z","caller":"traceutil/trace.go:171","msg":"trace[1994688033] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"134.558495ms","start":"2026-03-26T14:06:18.58813Z","end":"2026-03-26T14:06:18.722688Z","steps":["trace[1994688033] 'read index received' (duration: 134.34662ms)","trace[1994688033] 'applied index is now lower than readState.Index' (duration: 210.695µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T14:06:18.722834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.68785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T14:06:18.722885Z","caller":"traceutil/trace.go:171","msg":"trace[979978857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1877; }","duration":"134.798892ms","start":"2026-03-26T14:06:18.588073Z","end":"2026-03-26T14:06:18.722872Z","steps":["trace[979978857] 'agreement among raft nodes before linearized reading' (duration: 134.70511ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:06:19.165612Z","caller":"traceutil/trace.go:171","msg":"trace[1589269036] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"204.075248ms","start":"2026-03-26T14:06:18.961513Z","end":"2026-03-26T14:06:19.165588Z","steps":["trace[1589269036] 'process raft request' (duration: 203.932014ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:06:19.206264Z","caller":"traceutil/trace.go:171","msg":"trace[1058583262] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"146.521874ms","start":"2026-03-26T14:06:19.059718Z","end":"2026-03-26T14:06:19.20624Z","steps":["trace[1058583262] 'process raft request' (duration: 146.404531ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:06:30.297284Z","caller":"traceutil/trace.go:171","msg":"trace[1026913364] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"129.858633ms","start":"2026-03-26T14:06:30.167382Z","end":"2026-03-26T14:06:30.297241Z","steps":["trace[1026913364] 'process raft request' (duration: 129.702229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:06:30.44669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.986543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T14:06:30.446855Z","caller":"traceutil/trace.go:171","msg":"trace[445416326] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"220.663837ms","start":"2026-03-26T14:06:30.226161Z","end":"2026-03-26T14:06:30.446825Z","steps":["trace[445416326] 'process raft request' (duration: 84.234082ms)","trace[445416326] 'compare' (duration: 135.841528ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T14:06:30.642526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.734376ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-26T14:06:30.64292Z","caller":"traceutil/trace.go:171","msg":"trace[189987174] linearizableReadLoop","detail":"{readStateIndex:2051; appliedIndex:2048; }","duration":"331.215717ms","start":"2026-03-26T14:06:30.31167Z","end":"2026-03-26T14:06:30.642885Z","steps":["trace[189987174] 'read index received' (duration: 45.082µs)","trace[189987174] 'applied index is now lower than readState.Index' (duration: 331.169635ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:06:30.643044Z","caller":"traceutil/trace.go:171","msg":"trace[1963559718] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"394.417435ms","start":"2026-03-26T14:06:30.248575Z","end":"2026-03-26T14:06:30.642992Z","steps":["trace[1963559718] 'process raft request' (duration: 394.065586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:06:30.643252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T14:06:30.24855Z","time spent":"394.60173ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T14:06:30.710246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.969049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T14:06:30.710329Z","caller":"traceutil/trace.go:171","msg":"trace[1547270680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1976; }","duration":"228.089483ms","start":"2026-03-26T14:06:30.482218Z","end":"2026-03-26T14:06:30.710307Z","steps":["trace[1547270680] 'agreement among raft nodes before linearized reading' (duration: 160.77666ms)","trace[1547270680] 'range keys from in-memory index tree' (duration: 67.216951ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T14:06:30.710246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.54346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:11294"} {"level":"info","ts":"2026-03-26T14:06:30.710598Z","caller":"traceutil/trace.go:171","msg":"trace[174008378] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1976; }","duration":"398.944631ms","start":"2026-03-26T14:06:30.311609Z","end":"2026-03-26T14:06:30.710554Z","steps":["trace[174008378] 'agreement among raft nodes before linearized reading' (duration: 331.3455ms)","trace[174008378] 'range keys from in-memory index tree' (duration: 67.085917ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T14:06:30.710726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T14:06:30.311588Z","time spent":"399.099826ms","remote":"127.0.0.1:59324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":11317,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"info","ts":"2026-03-26T14:06:33.910142Z","caller":"traceutil/trace.go:171","msg":"trace[1507952668] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"188.675041ms","start":"2026-03-26T14:06:33.721442Z","end":"2026-03-26T14:06:33.910117Z","steps":["trace[1507952668] 'process raft request' (duration: 188.488566ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:06:34.994095Z","caller":"traceutil/trace.go:171","msg":"trace[504902992] linearizableReadLoop","detail":"{readStateIndex:2087; appliedIndex:2086; }","duration":"157.533239ms","start":"2026-03-26T14:06:34.836539Z","end":"2026-03-26T14:06:34.994072Z","steps":["trace[504902992] 'read index received' (duration: 155.829682ms)","trace[504902992] 'applied index is now lower than readState.Index' (duration: 1.701867ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T14:06:34.994245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.700594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T14:06:34.994286Z","caller":"traceutil/trace.go:171","msg":"trace[1679025821] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2011; }","duration":"157.768126ms","start":"2026-03-26T14:06:34.836504Z","end":"2026-03-26T14:06:34.994272Z","steps":["trace[1679025821] 'agreement among raft nodes before linearized reading' (duration: 157.687933ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:07:07.108932Z","caller":"traceutil/trace.go:171","msg":"trace[1114333709] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"157.18951ms","start":"2026-03-26T14:07:06.951722Z","end":"2026-03-26T14:07:07.108911Z","steps":["trace[1114333709] 'read index received' (duration: 156.980635ms)","trace[1114333709] 'applied index is now lower than readState.Index' (duration: 208.235µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:07:07.108978Z","caller":"traceutil/trace.go:171","msg":"trace[690721411] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"172.909035ms","start":"2026-03-26T14:07:06.936047Z","end":"2026-03-26T14:07:07.108956Z","steps":["trace[690721411] 'process raft request' (duration: 172.727911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:07:07.109055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.320743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T14:07:07.109075Z","caller":"traceutil/trace.go:171","msg":"trace[1925134970] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2189; }","duration":"157.411996ms","start":"2026-03-26T14:07:06.951658Z","end":"2026-03-26T14:07:07.10907Z","steps":["trace[1925134970] 'agreement among raft nodes before linearized reading' (duration: 157.342594ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:07:07.39281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.288382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T14:07:07.392922Z","caller":"traceutil/trace.go:171","msg":"trace[1296180933] linearizableReadLoop","detail":"{readStateIndex:2272; appliedIndex:2271; }","duration":"212.659105ms","start":"2026-03-26T14:07:07.180244Z","end":"2026-03-26T14:07:07.392903Z","steps":["trace[1296180933] 'read index received' (duration: 85.182358ms)","trace[1296180933] 'applied index is now lower than readState.Index' (duration: 127.475157ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:07:07.393009Z","caller":"traceutil/trace.go:171","msg":"trace[1896642052] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"226.132589ms","start":"2026-03-26T14:07:07.16686Z","end":"2026-03-26T14:07:07.392993Z","steps":["trace[1896642052] 'process raft request' (duration: 98.578739ms)","trace[1896642052] 'compare' (duration: 126.984574ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T14:07:07.393174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.914693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T14:07:07.393255Z","caller":"traceutil/trace.go:171","msg":"trace[68802476] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2190; }","duration":"213.063377ms","start":"2026-03-26T14:07:07.180174Z","end":"2026-03-26T14:07:07.393237Z","steps":["trace[68802476] 'agreement among raft nodes before linearized reading' (duration: 212.904382ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:07:07.491204Z","caller":"traceutil/trace.go:171","msg":"trace[224904696] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"151.874613ms","start":"2026-03-26T14:07:07.339303Z","end":"2026-03-26T14:07:07.491177Z","steps":["trace[224904696] 'process raft request' (duration: 151.675138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:07:07.491233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.752111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-26T14:07:07.491293Z","caller":"traceutil/trace.go:171","msg":"trace[108108768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2191; }","duration":"288.856904ms","start":"2026-03-26T14:07:07.202421Z","end":"2026-03-26T14:07:07.491278Z","steps":["trace[108108768] 'agreement among raft nodes before linearized reading' (duration: 288.683339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:07:07.491448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.697827ms","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-03-26T14:07:07.491553Z","caller":"traceutil/trace.go:171","msg":"trace[214437221] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2191; }","duration":"203.861432ms","start":"2026-03-26T14:07:07.28767Z","end":"2026-03-26T14:07:07.491531Z","steps":["trace[214437221] 'agreement among raft nodes before linearized reading' (duration: 203.535543ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:07:07.604456Z","caller":"traceutil/trace.go:171","msg":"trace[1318839257] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"108.825692ms","start":"2026-03-26T14:07:07.495607Z","end":"2026-03-26T14:07:07.604432Z","steps":["trace[1318839257] 'process raft request' (duration: 108.76231ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:07:07.604481Z","caller":"traceutil/trace.go:171","msg":"trace[301888445] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"167.536396ms","start":"2026-03-26T14:07:07.436913Z","end":"2026-03-26T14:07:07.604449Z","steps":["trace[301888445] 'process raft request' (duration: 81.527596ms)","trace[301888445] 'compare' (duration: 85.753973ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T14:07:07.899823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.483359ms","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-03-26T14:07:07.899927Z","caller":"traceutil/trace.go:171","msg":"trace[1172295396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2193; }","duration":"144.647183ms","start":"2026-03-26T14:07:07.755256Z","end":"2026-03-26T14:07:07.899903Z","steps":["trace[1172295396] 'range keys from in-memory index tree' (duration: 144.167539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:07:14.270072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.744434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T14:07:14.270148Z","caller":"traceutil/trace.go:171","msg":"trace[1430622771] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2224; }","duration":"463.894248ms","start":"2026-03-26T14:07:13.80624Z","end":"2026-03-26T14:07:14.270134Z","steps":["trace[1430622771] 'count revisions from in-memory index tree' (duration: 463.592749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:07:14.27018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T14:07:13.806215Z","time spent":"463.95685ms","remote":"127.0.0.1:59108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-03-26T14:07:14.270263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.285814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T14:07:14.270353Z","caller":"traceutil/trace.go:171","msg":"trace[1163776956] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2224; }","duration":"419.476499ms","start":"2026-03-26T14:07:13.85086Z","end":"2026-03-26T14:07:14.270337Z","steps":["trace[1163776956] 'count revisions from in-memory index tree' (duration: 419.191021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:07:14.270402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T14:07:13.850838Z","time spent":"419.553771ms","remote":"127.0.0.1:59222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-03-26T14:07:14.2703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.451857ms","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-03-26T14:07:14.270484Z","caller":"traceutil/trace.go:171","msg":"trace[943436424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2224; }","duration":"316.686855ms","start":"2026-03-26T14:07:13.95379Z","end":"2026-03-26T14:07:14.270477Z","steps":["trace[943436424] 'range keys from in-memory index tree' (duration: 316.280333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:07:14.270507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T14:07:13.953768Z","time spent":"316.732255ms","remote":"127.0.0.1:59426","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-03-26T14:07:14.270469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.813317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T14:07:14.270803Z","caller":"traceutil/trace.go:171","msg":"trace[1878506341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2224; }","duration":"141.27142ms","start":"2026-03-26T14:07:14.129499Z","end":"2026-03-26T14:07:14.27077Z","steps":["trace[1878506341] 'range keys from in-memory index tree' (duration: 140.460027ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:07:14.526356Z","caller":"traceutil/trace.go:171","msg":"trace[190916495] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"250.330758ms","start":"2026-03-26T14:07:14.276Z","end":"2026-03-26T14:07:14.526331Z","steps":["trace[190916495] 'process raft request' (duration: 250.107002ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:07:14.526536Z","caller":"traceutil/trace.go:171","msg":"trace[1650507505] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"250.474903ms","start":"2026-03-26T14:07:14.276018Z","end":"2026-03-26T14:07:14.526493Z","steps":["trace[1650507505] 'process raft request' (duration: 250.266896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:07:14.924762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.743242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T14:07:14.924916Z","caller":"traceutil/trace.go:171","msg":"trace[203139427] linearizableReadLoop","detail":"{readStateIndex:2311; appliedIndex:2310; }","duration":"374.065233ms","start":"2026-03-26T14:07:14.550828Z","end":"2026-03-26T14:07:14.924894Z","steps":["trace[203139427] 'read index received' (duration: 210.068913ms)","trace[203139427] 'applied index is now lower than readState.Index' (duration: 163.99436ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:07:14.925019Z","caller":"traceutil/trace.go:171","msg":"trace[367628775] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"394.161479ms","start":"2026-03-26T14:07:14.530846Z","end":"2026-03-26T14:07:14.925007Z","steps":["trace[367628775] 'process raft request' (duration: 230.036866ms)","trace[367628775] 'compare' (duration: 163.548557ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T14:07:14.925109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T14:07:14.530829Z","time spent":"394.235441ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T14:07:14.925596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.757202ms","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-03-26T14:07:14.925672Z","caller":"traceutil/trace.go:171","msg":"trace[1539989414] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2227; }","duration":"374.865925ms","start":"2026-03-26T14:07:14.550783Z","end":"2026-03-26T14:07:14.925649Z","steps":["trace[1539989414] 'agreement among raft nodes before linearized reading' (duration: 374.231167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:07:14.925728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T14:07:14.550764Z","time spent":"374.951666ms","remote":"127.0.0.1:35074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-03-26T14:07:14.944121Z","caller":"traceutil/trace.go:171","msg":"trace[419250966] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"345.903454ms","start":"2026-03-26T14:07:14.598196Z","end":"2026-03-26T14:07:14.9441Z","steps":["trace[419250966] 'process raft request' (duration: 345.76132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:07:14.94414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.190358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-26T14:07:14.944193Z","caller":"traceutil/trace.go:171","msg":"trace[1001209806] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:2228; }","duration":"111.287241ms","start":"2026-03-26T14:07:14.83289Z","end":"2026-03-26T14:07:14.944177Z","steps":["trace[1001209806] 'agreement among raft nodes before linearized reading' (duration: 111.152797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:07:14.94423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T14:07:14.59817Z","time spent":"346.004556ms","remote":"127.0.0.1:59426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T14:07:35.317638Z","caller":"traceutil/trace.go:171","msg":"trace[1754978663] transaction","detail":"{read_only:false; response_revision:2357; number_of_response:1; }","duration":"115.364993ms","start":"2026-03-26T14:07:35.202243Z","end":"2026-03-26T14:07:35.317608Z","steps":["trace[1754978663] 'process raft request' (duration: 86.395222ms)","trace[1754978663] 'compare' (duration: 28.832367ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:08:01.86108Z","caller":"traceutil/trace.go:171","msg":"trace[706738781] linearizableReadLoop","detail":"{readStateIndex:2753; appliedIndex:2752; }","duration":"219.71763ms","start":"2026-03-26T14:08:01.641334Z","end":"2026-03-26T14:08:01.861052Z","steps":["trace[706738781] 'read index received' (duration: 219.655328ms)","trace[706738781] 'applied index is now lower than readState.Index' (duration: 61.222µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:08:01.861185Z","caller":"traceutil/trace.go:171","msg":"trace[345664340] transaction","detail":"{read_only:false; response_revision:2653; number_of_response:1; }","duration":"252.784805ms","start":"2026-03-26T14:08:01.608366Z","end":"2026-03-26T14:08:01.861151Z","steps":["trace[345664340] 'process raft request' (duration: 252.454326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:08:01.861269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.903735ms","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-03-26T14:08:01.86136Z","caller":"traceutil/trace.go:171","msg":"trace[1201272655] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2653; }","duration":"220.047639ms","start":"2026-03-26T14:08:01.641291Z","end":"2026-03-26T14:08:01.861339Z","steps":["trace[1201272655] 'agreement among raft nodes before linearized reading' (duration: 219.883635ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:08:01.862967Z","caller":"traceutil/trace.go:171","msg":"trace[171404188] transaction","detail":"{read_only:false; response_revision:2654; number_of_response:1; }","duration":"134.666406ms","start":"2026-03-26T14:08:01.728259Z","end":"2026-03-26T14:08:01.862925Z","steps":["trace[171404188] 'process raft request' (duration: 134.464061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:08:01.863011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.647922ms","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-03-26T14:08:01.863144Z","caller":"traceutil/trace.go:171","msg":"trace[2087042935] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2654; }","duration":"119.830107ms","start":"2026-03-26T14:08:01.743305Z","end":"2026-03-26T14:08:01.863135Z","steps":["trace[2087042935] 'agreement among raft nodes before linearized reading' (duration: 119.532079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:08:30.184002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.859568ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T14:08:30.184176Z","caller":"traceutil/trace.go:171","msg":"trace[272401745] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2829; }","duration":"102.048832ms","start":"2026-03-26T14:08:30.082107Z","end":"2026-03-26T14:08:30.184156Z","steps":["trace[272401745] 'range keys from in-memory index tree' (duration: 101.845468ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:08:30.184726Z","caller":"traceutil/trace.go:171","msg":"trace[965817267] transaction","detail":"{read_only:false; response_revision:2830; number_of_response:1; }","duration":"114.822368ms","start":"2026-03-26T14:08:30.069835Z","end":"2026-03-26T14:08:30.184657Z","steps":["trace[965817267] 'process raft request' (duration: 44.522946ms)","trace[965817267] 'compare' (duration: 69.680908ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T14:08:35.912052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.325225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-03-26T14:08:35.912142Z","caller":"traceutil/trace.go:171","msg":"trace[763510055] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2862; }","duration":"134.477389ms","start":"2026-03-26T14:08:35.777646Z","end":"2026-03-26T14:08:35.912123Z","steps":["trace[763510055] 'range keys from in-memory index tree' (duration: 133.912904ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:11:16.71587Z","caller":"traceutil/trace.go:171","msg":"trace[2037585497] transaction","detail":"{read_only:false; response_revision:3909; number_of_response:1; }","duration":"130.915135ms","start":"2026-03-26T14:11:16.584926Z","end":"2026-03-26T14:11:16.715841Z","steps":["trace[2037585497] 'process raft request' (duration: 130.722081ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:12:32.119282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.019476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T14:12:32.119397Z","caller":"traceutil/trace.go:171","msg":"trace[198712594] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4789; }","duration":"100.146728ms","start":"2026-03-26T14:12:32.019215Z","end":"2026-03-26T14:12:32.119362Z","steps":["trace[198712594] 'agreement among raft nodes before linearized reading' (duration: 99.977024ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:12:40.348075Z","caller":"traceutil/trace.go:171","msg":"trace[1163590278] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"202.736525ms","start":"2026-03-26T14:12:40.145306Z","end":"2026-03-26T14:12:40.348043Z","steps":["trace[1163590278] 'process raft request' (duration: 202.581061ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:12:40.348094Z","caller":"traceutil/trace.go:171","msg":"trace[1510538731] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"256.734489ms","start":"2026-03-26T14:12:40.091308Z","end":"2026-03-26T14:12:40.348043Z","steps":["trace[1510538731] 'process raft request' (duration: 256.387951ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:12:40.374191Z","caller":"traceutil/trace.go:171","msg":"trace[372294878] linearizableReadLoop","detail":"{readStateIndex:5256; appliedIndex:5253; }","duration":"102.054873ms","start":"2026-03-26T14:12:40.272103Z","end":"2026-03-26T14:12:40.374158Z","steps":["trace[372294878] 'read index received' (duration: 75.61598ms)","trace[372294878] 'applied index is now lower than readState.Index' (duration: 26.437283ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T14:12:40.374682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.567037ms","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-03-26T14:12:40.37473Z","caller":"traceutil/trace.go:171","msg":"trace[1120063428] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5065; }","duration":"102.662969ms","start":"2026-03-26T14:12:40.272056Z","end":"2026-03-26T14:12:40.374719Z","steps":["trace[1120063428] 'agreement among raft nodes before linearized reading' (duration: 102.237068ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:12:42.473423Z","caller":"traceutil/trace.go:171","msg":"trace[1252415944] transaction","detail":"{read_only:false; response_revision:5099; number_of_response:1; }","duration":"119.00748ms","start":"2026-03-26T14:12:42.354387Z","end":"2026-03-26T14:12:42.473395Z","steps":["trace[1252415944] 'process raft request' (duration: 118.810995ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:12:51.861779Z","caller":"traceutil/trace.go:171","msg":"trace[685421226] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"132.58062ms","start":"2026-03-26T14:12:51.729172Z","end":"2026-03-26T14:12:51.861753Z","steps":["trace[685421226] 'process raft request' (duration: 73.572839ms)","trace[685421226] 'compare' (duration: 58.868547ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:13:02.54025Z","caller":"traceutil/trace.go:171","msg":"trace[1858600647] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"121.444553ms","start":"2026-03-26T14:13:02.418761Z","end":"2026-03-26T14:13:02.540205Z","steps":["trace[1858600647] 'process raft request' (duration: 121.129396ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:14:01.47795Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3001} {"level":"info","ts":"2026-03-26T14:14:01.561757Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3001,"took":"82.235108ms","hash":2332330913,"current-db-size-bytes":56565760,"current-db-size":"57 MB","current-db-size-in-use-bytes":31203328,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-26T14:14:01.561822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2332330913,"revision":3001,"compact-revision":-1} {"level":"info","ts":"2026-03-26T14:18:58.490924Z","caller":"traceutil/trace.go:171","msg":"trace[1719914787] transaction","detail":"{read_only:false; response_revision:8262; number_of_response:1; }","duration":"197.547647ms","start":"2026-03-26T14:18:58.293323Z","end":"2026-03-26T14:18:58.490871Z","steps":["trace[1719914787] 'process raft request' (duration: 197.367383ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:18:58.521884Z","caller":"traceutil/trace.go:171","msg":"trace[1780926632] linearizableReadLoop","detail":"{readStateIndex:8554; appliedIndex:8552; }","duration":"224.07336ms","start":"2026-03-26T14:18:58.297781Z","end":"2026-03-26T14:18:58.521854Z","steps":["trace[1780926632] 'read index received' (duration: 192.983036ms)","trace[1780926632] 'applied index is now lower than readState.Index' (duration: 31.089214ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:18:58.522005Z","caller":"traceutil/trace.go:171","msg":"trace[434410355] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"228.318222ms","start":"2026-03-26T14:18:58.293676Z","end":"2026-03-26T14:18:58.521994Z","steps":["trace[434410355] 'process raft request' (duration: 227.914233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:18:58.522128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.336657ms","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-03-26T14:18:58.522196Z","caller":"traceutil/trace.go:171","msg":"trace[293012970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8264; }","duration":"224.442819ms","start":"2026-03-26T14:18:58.297739Z","end":"2026-03-26T14:18:58.522182Z","steps":["trace[293012970] 'agreement among raft nodes before linearized reading' (duration: 224.258665ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:18:58.522378Z","caller":"traceutil/trace.go:171","msg":"trace[457676006] transaction","detail":"{read_only:false; response_revision:8264; number_of_response:1; }","duration":"144.583259ms","start":"2026-03-26T14:18:58.377786Z","end":"2026-03-26T14:18:58.522369Z","steps":["trace[457676006] 'process raft request' (duration: 143.978675ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:18:58.670117Z","caller":"traceutil/trace.go:171","msg":"trace[1657877172] transaction","detail":"{read_only:false; response_revision:8265; number_of_response:1; }","duration":"143.419111ms","start":"2026-03-26T14:18:58.526664Z","end":"2026-03-26T14:18:58.670083Z","steps":["trace[1657877172] 'process raft request' (duration: 143.192105ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:19:01.483367Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6053} {"level":"info","ts":"2026-03-26T14:19:01.579658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6053,"took":"93.420775ms","hash":241222255,"current-db-size-bytes":56565760,"current-db-size":"57 MB","current-db-size-in-use-bytes":23363584,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-26T14:19:01.579739Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":241222255,"revision":6053,"compact-revision":3001} {"level":"warn","ts":"2026-03-26T14:19:10.345817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.34545ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-26T14:19:12.012624Z","caller":"traceutil/trace.go:171","msg":"trace[970509381] transaction","detail":"{read_only:false; response_revision:8331; number_of_response:1; }","duration":"181.341228ms","start":"2026-03-26T14:19:11.831257Z","end":"2026-03-26T14:19:12.012598Z","steps":["trace[970509381] 'process raft request' (duration: 181.139123ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:19:12.693598Z","caller":"traceutil/trace.go:171","msg":"trace[513882355] transaction","detail":"{read_only:false; response_revision:8335; number_of_response:1; }","duration":"134.898137ms","start":"2026-03-26T14:19:12.558684Z","end":"2026-03-26T14:19:12.693582Z","steps":["trace[513882355] 'process raft request' (duration: 134.797604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:19:53.63156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.272331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T14:19:53.631665Z","caller":"traceutil/trace.go:171","msg":"trace[1984533869] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:8651; }","duration":"126.437925ms","start":"2026-03-26T14:19:53.505206Z","end":"2026-03-26T14:19:53.631644Z","steps":["trace[1984533869] 'count revisions from in-memory index tree' (duration: 126.177729ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:19:53.694345Z","caller":"traceutil/trace.go:171","msg":"trace[609289950] linearizableReadLoop","detail":"{readStateIndex:8960; appliedIndex:8959; }","duration":"104.998302ms","start":"2026-03-26T14:19:53.589313Z","end":"2026-03-26T14:19:53.694312Z","steps":["trace[609289950] 'read index received' (duration: 104.765927ms)","trace[609289950] 'applied index is now lower than readState.Index' (duration: 231.355µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:19:53.694408Z","caller":"traceutil/trace.go:171","msg":"trace[1028024987] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"105.280049ms","start":"2026-03-26T14:19:53.5891Z","end":"2026-03-26T14:19:53.69438Z","steps":["trace[1028024987] 'process raft request' (duration: 105.059053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:19:53.69482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.466154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-m4nr2\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-03-26T14:19:53.694992Z","caller":"traceutil/trace.go:171","msg":"trace[1719135525] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-m4nr2; range_end:; response_count:1; response_revision:8652; }","duration":"105.654288ms","start":"2026-03-26T14:19:53.589302Z","end":"2026-03-26T14:19:53.694956Z","steps":["trace[1719135525] 'agreement among raft nodes before linearized reading' (duration: 105.165797ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:19:53.936559Z","caller":"traceutil/trace.go:171","msg":"trace[835446595] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"235.833272ms","start":"2026-03-26T14:19:53.700667Z","end":"2026-03-26T14:19:53.9365Z","steps":["trace[835446595] 'process raft request' (duration: 209.016071ms)","trace[835446595] 'compare' (duration: 26.612656ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:19:53.93662Z","caller":"traceutil/trace.go:171","msg":"trace[2028718375] transaction","detail":"{read_only:false; response_revision:8655; number_of_response:1; }","duration":"231.352115ms","start":"2026-03-26T14:19:53.705235Z","end":"2026-03-26T14:19:53.936587Z","steps":["trace[2028718375] 'process raft request' (duration: 231.178851ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:19:54.797078Z","caller":"traceutil/trace.go:171","msg":"trace[669761635] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"163.253195ms","start":"2026-03-26T14:19:54.633796Z","end":"2026-03-26T14:19:54.79705Z","steps":["trace[669761635] 'process raft request' (duration: 163.04369ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:20:44.524497Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024c3.wal"} {"level":"info","ts":"2026-03-26T14:21:10.324013Z","caller":"traceutil/trace.go:171","msg":"trace[1053537510] linearizableReadLoop","detail":"{readStateIndex:9729; appliedIndex:9728; }","duration":"133.618244ms","start":"2026-03-26T14:21:10.19037Z","end":"2026-03-26T14:21:10.323988Z","steps":["trace[1053537510] 'read index received' (duration: 133.588163ms)","trace[1053537510] 'applied index is now lower than readState.Index' (duration: 28.761µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:21:10.324083Z","caller":"traceutil/trace.go:171","msg":"trace[332972511] transaction","detail":"{read_only:false; response_revision:9403; number_of_response:1; }","duration":"135.840288ms","start":"2026-03-26T14:21:10.188218Z","end":"2026-03-26T14:21:10.324058Z","steps":["trace[332972511] 'process raft request' (duration: 135.636003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:21:10.324275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.88169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kww8b\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-26T14:21:10.324327Z","caller":"traceutil/trace.go:171","msg":"trace[799787493] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kww8b; range_end:; response_count:1; response_revision:9403; }","duration":"133.984232ms","start":"2026-03-26T14:21:10.190328Z","end":"2026-03-26T14:21:10.324312Z","steps":["trace[799787493] 'agreement among raft nodes before linearized reading' (duration: 133.771627ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:21:45.382988Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-26T14:21:45.388164Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-26T14:21:45.388297Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-26T14:24:01.489069Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8278} {"level":"info","ts":"2026-03-26T14:24:01.579901Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8278,"took":"88.046738ms","hash":460590881,"current-db-size-bytes":56565760,"current-db-size":"57 MB","current-db-size-in-use-bytes":25907200,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-26T14:24:01.579955Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":460590881,"revision":8278,"compact-revision":6053} {"level":"info","ts":"2026-03-26T14:26:25.015263Z","caller":"traceutil/trace.go:171","msg":"trace[1228844959] linearizableReadLoop","detail":"{readStateIndex:11825; appliedIndex:11824; }","duration":"115.524704ms","start":"2026-03-26T14:26:24.899709Z","end":"2026-03-26T14:26:25.015233Z","steps":["trace[1228844959] 'read index received' (duration: 115.295118ms)","trace[1228844959] 'applied index is now lower than readState.Index' (duration: 228.565µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T14:26:25.015395Z","caller":"traceutil/trace.go:171","msg":"trace[1911848047] transaction","detail":"{read_only:false; response_revision:11427; number_of_response:1; }","duration":"118.586446ms","start":"2026-03-26T14:26:24.896772Z","end":"2026-03-26T14:26:25.015358Z","steps":["trace[1911848047] 'process raft request' (duration: 118.30716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:26:25.015521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.790371ms","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-03-26T14:26:25.01557Z","caller":"traceutil/trace.go:171","msg":"trace[1238452891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11427; }","duration":"115.892713ms","start":"2026-03-26T14:26:24.899667Z","end":"2026-03-26T14:26:25.01556Z","steps":["trace[1238452891] 'agreement among raft nodes before linearized reading' (duration: 115.730268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:26:25.015573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.164076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-03-26T14:26:25.015611Z","caller":"traceutil/trace.go:171","msg":"trace[457373468] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:11427; }","duration":"115.217687ms","start":"2026-03-26T14:26:24.900384Z","end":"2026-03-26T14:26:25.015602Z","steps":["trace[457373468] 'agreement among raft nodes before linearized reading' (duration: 115.121465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T14:26:25.015726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.648931ms","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-03-26T14:26:25.015812Z","caller":"traceutil/trace.go:171","msg":"trace[2010145017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11427; }","duration":"111.772314ms","start":"2026-03-26T14:26:24.90402Z","end":"2026-03-26T14:26:25.015792Z","steps":["trace[2010145017] 'agreement among raft nodes before linearized reading' (duration: 111.507097ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T14:29:01.514656Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10675} {"level":"info","ts":"2026-03-26T14:29:01.631448Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10675,"took":"114.090747ms","hash":3855052876,"current-db-size-bytes":56565760,"current-db-size":"57 MB","current-db-size-in-use-bytes":20996096,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-03-26T14:29:01.631538Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3855052876,"revision":10675,"compact-revision":8278}