{"level":"warn","ts":"2026-04-20T11:52:41.537303Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T11:52:41.53746Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-04-20T11:52:41.537539Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T11:52:41.537551Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-20T11:52:41.537582Z","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-04-20T11:52:41.53836Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-04-20T11:52:41.538441Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-04-20T11:52:41.543157Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.506501ms"} {"level":"info","ts":"2026-04-20T11:52:41.54847Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-04-20T11:52:41.548564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T11:52:41.548591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-04-20T11:52:41.548608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T11:52:41.548621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-04-20T11:52:41.548673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-04-20T11:52:41.556667Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T11:52:41.558304Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T11:52:41.560057Z","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-04-20T11:52:41.564711Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T11:52:41.565014Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T11:52:41.565072Z","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-04-20T11:52:41.565205Z","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-04-20T11:52:41.565217Z","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-04-20T11:52:41.567829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-04-20T11:52:41.567979Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-20T11:52:41.569448Z","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-04-20T11:52:41.569621Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-20T11:52:41.569659Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-20T11:52:41.569864Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T11:52:41.569925Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T11:52:42.448948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T11:52:42.44902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T11:52:42.449097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-04-20T11:52:42.44912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-04-20T11:52:42.449131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-20T11:52:42.449156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-04-20T11:52:42.44917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-20T11:52:42.450456Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:52:42.451577Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T11:52:42.451645Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:52:42.451688Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:52:42.452028Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T11:52:42.452133Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T11:52:42.455321Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T11:52:42.455349Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-04-20T11:52:42.456187Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:52:42.456347Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:52:42.456399Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:52:43.890584Z","caller":"traceutil/trace.go:171","msg":"trace[702271167] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"123.612754ms","start":"2026-04-20T11:52:43.766945Z","end":"2026-04-20T11:52:43.890558Z","steps":["trace[702271167] 'read index received' (duration: 69.117348ms)","trace[702271167] 'applied index is now lower than readState.Index' (duration: 54.494406ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:52:43.890931Z","caller":"traceutil/trace.go:171","msg":"trace[1833334696] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"123.608894ms","start":"2026-04-20T11:52:43.766978Z","end":"2026-04-20T11:52:43.890586Z","steps":["trace[1833334696] 'process raft request' (duration: 123.153451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:43.890753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.791798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T11:52:43.891064Z","caller":"traceutil/trace.go:171","msg":"trace[19243314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3; }","duration":"124.126128ms","start":"2026-04-20T11:52:43.766922Z","end":"2026-04-20T11:52:43.891048Z","steps":["trace[19243314] 'agreement among raft nodes before linearized reading' (duration: 123.746278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:43.920728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.547463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T11:52:43.920834Z","caller":"traceutil/trace.go:171","msg":"trace[481599331] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"144.716337ms","start":"2026-04-20T11:52:43.776092Z","end":"2026-04-20T11:52:43.920808Z","steps":["trace[481599331] 'agreement among raft nodes before linearized reading' (duration: 144.528402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:43.921267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.27856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T11:52:43.921315Z","caller":"traceutil/trace.go:171","msg":"trace[1998380376] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"106.353522ms","start":"2026-04-20T11:52:43.814949Z","end":"2026-04-20T11:52:43.921303Z","steps":["trace[1998380376] 'agreement among raft nodes before linearized reading' (duration: 106.28793ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:45.869747Z","caller":"traceutil/trace.go:171","msg":"trace[429261141] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"132.889332ms","start":"2026-04-20T11:52:45.736803Z","end":"2026-04-20T11:52:45.869693Z","steps":["trace[429261141] 'process raft request' (duration: 126.45611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:51.325078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.85652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-20T11:52:51.325188Z","caller":"traceutil/trace.go:171","msg":"trace[2025932652] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:340; }","duration":"173.078306ms","start":"2026-04-20T11:52:51.15209Z","end":"2026-04-20T11:52:51.325168Z","steps":["trace[2025932652] 'range keys from in-memory index tree' (duration: 172.709706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:51.325469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.917256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-20T11:52:51.325674Z","caller":"traceutil/trace.go:171","msg":"trace[1729882634] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:340; }","duration":"129.135952ms","start":"2026-04-20T11:52:51.1965Z","end":"2026-04-20T11:52:51.325636Z","steps":["trace[1729882634] 'range keys from in-memory index tree' (duration: 128.460493ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:51.968837Z","caller":"traceutil/trace.go:171","msg":"trace[1647146752] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"223.105713ms","start":"2026-04-20T11:52:51.745704Z","end":"2026-04-20T11:52:51.96881Z","steps":["trace[1647146752] 'read index received' (duration: 222.781094ms)","trace[1647146752] 'applied index is now lower than readState.Index' (duration: 323.409µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:52:51.968947Z","caller":"traceutil/trace.go:171","msg":"trace[1147440277] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"263.199884ms","start":"2026-04-20T11:52:51.705712Z","end":"2026-04-20T11:52:51.968912Z","steps":["trace[1147440277] 'process raft request' (duration: 262.844845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:51.969041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.544798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-20T11:52:51.969123Z","caller":"traceutil/trace.go:171","msg":"trace[1142249725] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:342; }","duration":"173.713713ms","start":"2026-04-20T11:52:51.795396Z","end":"2026-04-20T11:52:51.96911Z","steps":["trace[1142249725] 'agreement among raft nodes before linearized reading' (duration: 173.575059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:51.969215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.14742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-04-20T11:52:51.969281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.60478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4201"} {"level":"warn","ts":"2026-04-20T11:52:51.969252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.517514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-04-20T11:52:51.969351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.2326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-04-20T11:52:51.969461Z","caller":"traceutil/trace.go:171","msg":"trace[752709157] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:342; }","duration":"167.374773ms","start":"2026-04-20T11:52:51.802057Z","end":"2026-04-20T11:52:51.969431Z","steps":["trace[752709157] 'agreement among raft nodes before linearized reading' (duration: 167.132687ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:51.969464Z","caller":"traceutil/trace.go:171","msg":"trace[279696558] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:342; }","duration":"223.72753ms","start":"2026-04-20T11:52:51.745678Z","end":"2026-04-20T11:52:51.969406Z","steps":["trace[279696558] 'agreement among raft nodes before linearized reading' (duration: 223.404631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:51.969588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.031785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-20T11:52:51.969631Z","caller":"traceutil/trace.go:171","msg":"trace[30423471] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:342; }","duration":"124.132617ms","start":"2026-04-20T11:52:51.845487Z","end":"2026-04-20T11:52:51.96962Z","steps":["trace[30423471] 'agreement among raft nodes before linearized reading' (duration: 124.047655ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:51.969343Z","caller":"traceutil/trace.go:171","msg":"trace[1304459095] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:342; }","duration":"167.694132ms","start":"2026-04-20T11:52:51.801632Z","end":"2026-04-20T11:52:51.969326Z","steps":["trace[1304459095] 'agreement among raft nodes before linearized reading' (duration: 167.560319ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:51.969291Z","caller":"traceutil/trace.go:171","msg":"trace[1841564831] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:342; }","duration":"166.286624ms","start":"2026-04-20T11:52:51.802986Z","end":"2026-04-20T11:52:51.969273Z","steps":["trace[1841564831] 'agreement among raft nodes before linearized reading' (duration: 166.11905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:51.9698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.901721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4664"} {"level":"info","ts":"2026-04-20T11:52:51.970018Z","caller":"traceutil/trace.go:171","msg":"trace[1561959365] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:342; }","duration":"167.074526ms","start":"2026-04-20T11:52:51.802855Z","end":"2026-04-20T11:52:51.969929Z","steps":["trace[1561959365] 'agreement among raft nodes before linearized reading' (duration: 166.905241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:51.969998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.474076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-04-20T11:52:51.970154Z","caller":"traceutil/trace.go:171","msg":"trace[2144319166] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:342; }","duration":"167.664512ms","start":"2026-04-20T11:52:51.802453Z","end":"2026-04-20T11:52:51.970118Z","steps":["trace[2144319166] 'agreement among raft nodes before linearized reading' (duration: 167.340893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:51.970045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.069005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-20T11:52:51.970247Z","caller":"traceutil/trace.go:171","msg":"trace[1012666821] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:342; }","duration":"167.380693ms","start":"2026-04-20T11:52:51.802842Z","end":"2026-04-20T11:52:51.970223Z","steps":["trace[1012666821] 'agreement among raft nodes before linearized reading' (duration: 166.990943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:51.96982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.643724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-04-20T11:52:51.970442Z","caller":"traceutil/trace.go:171","msg":"trace[898909673] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:342; }","duration":"167.273911ms","start":"2026-04-20T11:52:51.803145Z","end":"2026-04-20T11:52:51.970419Z","steps":["trace[898909673] 'agreement among raft nodes before linearized reading' (duration: 166.51489ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:19.737952Z","caller":"traceutil/trace.go:171","msg":"trace[459175808] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"131.253974ms","start":"2026-04-20T11:53:19.606662Z","end":"2026-04-20T11:53:19.737916Z","steps":["trace[459175808] 'process raft request' (duration: 130.533685ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:19.986145Z","caller":"traceutil/trace.go:171","msg":"trace[1213594229] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"190.599543ms","start":"2026-04-20T11:53:19.795507Z","end":"2026-04-20T11:53:19.986106Z","steps":["trace[1213594229] 'process raft request' (duration: 190.386628ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:20.245626Z","caller":"traceutil/trace.go:171","msg":"trace[1834739550] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"245.136565ms","start":"2026-04-20T11:53:20.000453Z","end":"2026-04-20T11:53:20.245589Z","steps":["trace[1834739550] 'read index received' (duration: 197.420365ms)","trace[1834739550] 'applied index is now lower than readState.Index' (duration: 47.71469ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:53:20.245703Z","caller":"traceutil/trace.go:171","msg":"trace[1725798705] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"399.876685ms","start":"2026-04-20T11:53:19.845793Z","end":"2026-04-20T11:53:20.24567Z","steps":["trace[1725798705] 'process raft request' (duration: 352.132584ms)","trace[1725798705] 'compare' (duration: 47.505415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:53:20.245844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.362412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:53:20.245937Z","caller":"traceutil/trace.go:171","msg":"trace[1965492634] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:621; }","duration":"245.533706ms","start":"2026-04-20T11:53:20.000379Z","end":"2026-04-20T11:53:20.245913Z","steps":["trace[1965492634] 'agreement among raft nodes before linearized reading' (duration: 245.385022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:20.246343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:53:19.845755Z","time spent":"400.019589ms","remote":"127.0.0.1:51404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:53:20.719693Z","caller":"traceutil/trace.go:171","msg":"trace[1102696211] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"120.801286ms","start":"2026-04-20T11:53:20.598844Z","end":"2026-04-20T11:53:20.719645Z","steps":["trace[1102696211] 'process raft request' (duration: 120.443586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:20.719998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.144026ms","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-04-20T11:53:20.720186Z","caller":"traceutil/trace.go:171","msg":"trace[206229971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:622; }","duration":"100.428183ms","start":"2026-04-20T11:53:20.619732Z","end":"2026-04-20T11:53:20.72016Z","steps":["trace[206229971] 'agreement among raft nodes before linearized reading' (duration: 99.887829ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:21.070596Z","caller":"traceutil/trace.go:171","msg":"trace[1625102535] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"439.620423ms","start":"2026-04-20T11:53:20.630938Z","end":"2026-04-20T11:53:21.070559Z","steps":["trace[1625102535] 'process raft request' (duration: 437.512247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:21.070819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:53:20.630907Z","time spent":"439.797888ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:53:21.074051Z","caller":"traceutil/trace.go:171","msg":"trace[262216812] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"139.130894ms","start":"2026-04-20T11:53:20.93488Z","end":"2026-04-20T11:53:21.074011Z","steps":["trace[262216812] 'read index received' (duration: 133.135774ms)","trace[262216812] 'applied index is now lower than readState.Index' (duration: 5.99353ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:53:21.07415Z","caller":"traceutil/trace.go:171","msg":"trace[527800688] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"348.98934ms","start":"2026-04-20T11:53:20.725123Z","end":"2026-04-20T11:53:21.074113Z","steps":["trace[527800688] 'process raft request' (duration: 348.703842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:21.074299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:53:20.725102Z","time spent":"349.097543ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:53:21.074307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.417571ms","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-04-20T11:53:21.074404Z","caller":"traceutil/trace.go:171","msg":"trace[167153213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"139.562425ms","start":"2026-04-20T11:53:20.934823Z","end":"2026-04-20T11:53:21.074385Z","steps":["trace[167153213] 'agreement among raft nodes before linearized reading' (duration: 139.324309ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:55.473961Z","caller":"traceutil/trace.go:171","msg":"trace[1443090645] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"104.80748ms","start":"2026-04-20T11:53:55.369124Z","end":"2026-04-20T11:53:55.473931Z","steps":["trace[1443090645] 'process raft request' (duration: 104.678327ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:55.473962Z","caller":"traceutil/trace.go:171","msg":"trace[965011409] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"133.709739ms","start":"2026-04-20T11:53:55.340214Z","end":"2026-04-20T11:53:55.473924Z","steps":["trace[965011409] 'process raft request' (duration: 42.633155ms)","trace[965011409] 'compare' (duration: 90.763956ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:53:55.474165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.684729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:53:55.474208Z","caller":"traceutil/trace.go:171","msg":"trace[463854232] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:1118; }","duration":"112.789822ms","start":"2026-04-20T11:53:55.361409Z","end":"2026-04-20T11:53:55.474198Z","steps":["trace[463854232] 'agreement among raft nodes before linearized reading' (duration: 112.663009ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:55.474019Z","caller":"traceutil/trace.go:171","msg":"trace[577396477] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"112.541935ms","start":"2026-04-20T11:53:55.361439Z","end":"2026-04-20T11:53:55.473981Z","steps":["trace[577396477] 'read index received' (duration: 21.413ms)","trace[577396477] 'applied index is now lower than readState.Index' (duration: 91.122275ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:53:55.474473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.943596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T11:53:55.474548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.614637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:53:55.47459Z","caller":"traceutil/trace.go:171","msg":"trace[1224297679] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:1118; }","duration":"113.07118ms","start":"2026-04-20T11:53:55.361476Z","end":"2026-04-20T11:53:55.474547Z","steps":["trace[1224297679] 'agreement among raft nodes before linearized reading' (duration: 112.895865ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:55.474651Z","caller":"traceutil/trace.go:171","msg":"trace[478485528] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:1118; }","duration":"112.753112ms","start":"2026-04-20T11:53:55.361872Z","end":"2026-04-20T11:53:55.474626Z","steps":["trace[478485528] 'agreement among raft nodes before linearized reading' (duration: 112.553716ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:54:49.803337Z","caller":"traceutil/trace.go:171","msg":"trace[98586744] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"225.310531ms","start":"2026-04-20T11:54:49.577999Z","end":"2026-04-20T11:54:49.803309Z","steps":["trace[98586744] 'process raft request' (duration: 225.131526ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:54:50.008923Z","caller":"traceutil/trace.go:171","msg":"trace[1369407259] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"429.487139ms","start":"2026-04-20T11:54:49.579417Z","end":"2026-04-20T11:54:50.008904Z","steps":["trace[1369407259] 'process raft request' (duration: 421.931987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:54:50.009095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:54:49.579386Z","time spent":"429.598402ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:54:50.00913Z","caller":"traceutil/trace.go:171","msg":"trace[1417877108] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1903; }","duration":"369.995192ms","start":"2026-04-20T11:54:49.639106Z","end":"2026-04-20T11:54:50.009101Z","steps":["trace[1417877108] 'read index received' (duration: 164.243982ms)","trace[1417877108] 'applied index is now lower than readState.Index' (duration: 205.74921ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:54:50.009206Z","caller":"traceutil/trace.go:171","msg":"trace[60538992] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"337.486814ms","start":"2026-04-20T11:54:49.671695Z","end":"2026-04-20T11:54:50.009181Z","steps":["trace[60538992] 'process raft request' (duration: 337.154015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:54:50.009312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.171806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T11:54:50.009357Z","caller":"traceutil/trace.go:171","msg":"trace[1949411963] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1833; }","duration":"370.266409ms","start":"2026-04-20T11:54:49.639076Z","end":"2026-04-20T11:54:50.009343Z","steps":["trace[1949411963] 'agreement among raft nodes before linearized reading' (duration: 370.164127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:54:50.009354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:54:49.671674Z","time spent":"337.589547ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:54:50.009392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:54:49.639059Z","time spent":"370.32459ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":2,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-20T11:54:50.009446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.33895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:54:50.009535Z","caller":"traceutil/trace.go:171","msg":"trace[524901437] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1833; }","duration":"304.455333ms","start":"2026-04-20T11:54:49.705029Z","end":"2026-04-20T11:54:50.009485Z","steps":["trace[524901437] 'agreement among raft nodes before linearized reading' (duration: 304.375451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:54:50.009576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:54:49.705012Z","time spent":"304.553655ms","remote":"127.0.0.1:45094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T11:54:50.009824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.209499ms","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-04-20T11:54:50.009859Z","caller":"traceutil/trace.go:171","msg":"trace[843044063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1833; }","duration":"261.274651ms","start":"2026-04-20T11:54:49.748576Z","end":"2026-04-20T11:54:50.00985Z","steps":["trace[843044063] 'agreement among raft nodes before linearized reading' (duration: 261.158498ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:54:50.444654Z","caller":"traceutil/trace.go:171","msg":"trace[953018859] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"172.04163ms","start":"2026-04-20T11:54:50.272577Z","end":"2026-04-20T11:54:50.444619Z","steps":["trace[953018859] 'process raft request' (duration: 171.839195ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:54:50.452141Z","caller":"traceutil/trace.go:171","msg":"trace[1916445953] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"145.78755ms","start":"2026-04-20T11:54:50.306337Z","end":"2026-04-20T11:54:50.452125Z","steps":["trace[1916445953] 'process raft request' (duration: 145.649706ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:03.556398Z","caller":"traceutil/trace.go:171","msg":"trace[559630416] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"105.929867ms","start":"2026-04-20T11:55:03.450441Z","end":"2026-04-20T11:55:03.556371Z","steps":["trace[559630416] 'process raft request' (duration: 105.761112ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:35.097142Z","caller":"traceutil/trace.go:171","msg":"trace[38472582] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"122.35542ms","start":"2026-04-20T11:55:34.974758Z","end":"2026-04-20T11:55:35.097114Z","steps":["trace[38472582] 'process raft request' (duration: 122.193586ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:35.833871Z","caller":"traceutil/trace.go:171","msg":"trace[914178400] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"128.685551ms","start":"2026-04-20T11:55:35.705137Z","end":"2026-04-20T11:55:35.833822Z","steps":["trace[914178400] 'process raft request' (duration: 128.399843ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:35.969318Z","caller":"traceutil/trace.go:171","msg":"trace[759743637] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"131.374454ms","start":"2026-04-20T11:55:35.837895Z","end":"2026-04-20T11:55:35.96927Z","steps":["trace[759743637] 'process raft request' (duration: 117.065429ms)","trace[759743637] 'compare' (duration: 13.967456ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:55:41.302758Z","caller":"traceutil/trace.go:171","msg":"trace[636090542] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2248; }","duration":"147.019344ms","start":"2026-04-20T11:55:41.15571Z","end":"2026-04-20T11:55:41.302729Z","steps":["trace[636090542] 'read index received' (duration: 147.008174ms)","trace[636090542] 'applied index is now lower than readState.Index' (duration: 8.77µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:55:41.302979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.24076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:55:41.303066Z","caller":"traceutil/trace.go:171","msg":"trace[1349494820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2166; }","duration":"147.394384ms","start":"2026-04-20T11:55:41.155657Z","end":"2026-04-20T11:55:41.303052Z","steps":["trace[1349494820] 'agreement among raft nodes before linearized reading' (duration: 147.157758ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:41.302674Z","caller":"traceutil/trace.go:171","msg":"trace[1222930441] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"185.51376ms","start":"2026-04-20T11:55:41.117118Z","end":"2026-04-20T11:55:41.302631Z","steps":["trace[1222930441] 'process raft request' (duration: 185.239422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:41.389378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.022404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T11:55:41.389479Z","caller":"traceutil/trace.go:171","msg":"trace[445929992] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2166; }","duration":"208.174409ms","start":"2026-04-20T11:55:41.181285Z","end":"2026-04-20T11:55:41.38946Z","steps":["trace[445929992] 'agreement among raft nodes before linearized reading' (duration: 207.990394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:41.389377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.883135ms","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-04-20T11:55:41.389591Z","caller":"traceutil/trace.go:171","msg":"trace[1414954733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2166; }","duration":"139.139832ms","start":"2026-04-20T11:55:41.250436Z","end":"2026-04-20T11:55:41.389576Z","steps":["trace[1414954733] 'agreement among raft nodes before linearized reading' (duration: 138.770462ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:04.04243Z","caller":"traceutil/trace.go:171","msg":"trace[170227098] transaction","detail":"{read_only:false; response_revision:2807; number_of_response:1; }","duration":"125.043577ms","start":"2026-04-20T11:57:03.91736Z","end":"2026-04-20T11:57:04.042403Z","steps":["trace[170227098] 'process raft request' (duration: 124.895813ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:04.04354Z","caller":"traceutil/trace.go:171","msg":"trace[655304553] transaction","detail":"{read_only:false; response_revision:2808; number_of_response:1; }","duration":"125.860018ms","start":"2026-04-20T11:57:03.917637Z","end":"2026-04-20T11:57:04.043497Z","steps":["trace[655304553] 'process raft request' (duration: 125.601131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:59:29.395952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.461771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-20T11:59:29.396063Z","caller":"traceutil/trace.go:171","msg":"trace[1136721907] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3774; }","duration":"150.622735ms","start":"2026-04-20T11:59:29.245416Z","end":"2026-04-20T11:59:29.396038Z","steps":["trace[1136721907] 'range keys from in-memory index tree' (duration: 150.270016ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:59:29.524145Z","caller":"traceutil/trace.go:171","msg":"trace[1043613732] transaction","detail":"{read_only:false; response_revision:3775; number_of_response:1; }","duration":"123.865642ms","start":"2026-04-20T11:59:29.400254Z","end":"2026-04-20T11:59:29.52412Z","steps":["trace[1043613732] 'process raft request' (duration: 123.665846ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:59:29.534201Z","caller":"traceutil/trace.go:171","msg":"trace[181578205] transaction","detail":"{read_only:false; response_revision:3776; number_of_response:1; }","duration":"133.818437ms","start":"2026-04-20T11:59:29.400347Z","end":"2026-04-20T11:59:29.534165Z","steps":["trace[181578205] 'process raft request' (duration: 133.623172ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:59:29.534318Z","caller":"traceutil/trace.go:171","msg":"trace[608369754] transaction","detail":"{read_only:false; response_revision:3777; number_of_response:1; }","duration":"133.574521ms","start":"2026-04-20T11:59:29.400715Z","end":"2026-04-20T11:59:29.534289Z","steps":["trace[608369754] 'process raft request' (duration: 133.404356ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:54.528776Z","caller":"traceutil/trace.go:171","msg":"trace[991807504] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"126.16474ms","start":"2026-04-20T12:00:54.402567Z","end":"2026-04-20T12:00:54.528732Z","steps":["trace[991807504] 'process raft request' (duration: 110.023759ms)","trace[991807504] 'compare' (duration: 15.919145ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:00:55.812378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.092115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:00:55.812661Z","caller":"traceutil/trace.go:171","msg":"trace[1387112156] linearizableReadLoop","detail":"{readStateIndex:5181; appliedIndex:5179; }","duration":"175.528879ms","start":"2026-04-20T12:00:55.63711Z","end":"2026-04-20T12:00:55.812639Z","steps":["trace[1387112156] 'read index received' (duration: 32.890598ms)","trace[1387112156] 'applied index is now lower than readState.Index' (duration: 142.636821ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:00:55.812773Z","caller":"traceutil/trace.go:171","msg":"trace[1678611717] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"195.124372ms","start":"2026-04-20T12:00:55.617622Z","end":"2026-04-20T12:00:55.812746Z","steps":["trace[1678611717] 'process raft request' (duration: 194.915197ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:55.812915Z","caller":"traceutil/trace.go:171","msg":"trace[934816536] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"198.016839ms","start":"2026-04-20T12:00:55.614839Z","end":"2026-04-20T12:00:55.812856Z","steps":["trace[934816536] 'process raft request' (duration: 55.149633ms)","trace[934816536] 'compare' (duration: 141.929911ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:00:55.813335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.217437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:26 size:787709"} {"level":"info","ts":"2026-04-20T12:00:55.813402Z","caller":"traceutil/trace.go:171","msg":"trace[1414223530] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:26; response_revision:4992; }","duration":"176.35174ms","start":"2026-04-20T12:00:55.637031Z","end":"2026-04-20T12:00:55.813383Z","steps":["trace[1414223530] 'agreement among raft nodes before linearized reading' (duration: 175.732704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:01:01.139998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.881374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m8d8q\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-20T12:01:01.140061Z","caller":"traceutil/trace.go:171","msg":"trace[1881677001] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m8d8q; range_end:; response_count:1; response_revision:5074; }","duration":"228.992197ms","start":"2026-04-20T12:01:00.911055Z","end":"2026-04-20T12:01:01.140047Z","steps":["trace[1881677001] 'range keys from in-memory index tree' (duration: 228.719409ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:01.200747Z","caller":"traceutil/trace.go:171","msg":"trace[71051286] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"154.405455ms","start":"2026-04-20T12:01:01.046317Z","end":"2026-04-20T12:01:01.200722Z","steps":["trace[71051286] 'process raft request' (duration: 154.078626ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:01.483691Z","caller":"traceutil/trace.go:171","msg":"trace[533556250] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"145.305241ms","start":"2026-04-20T12:01:01.338358Z","end":"2026-04-20T12:01:01.483663Z","steps":["trace[533556250] 'process raft request' (duration: 145.140296ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:01.566919Z","caller":"traceutil/trace.go:171","msg":"trace[1566427557] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"228.092732ms","start":"2026-04-20T12:01:01.338802Z","end":"2026-04-20T12:01:01.566895Z","steps":["trace[1566427557] 'process raft request' (duration: 227.861056ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:01.567214Z","caller":"traceutil/trace.go:171","msg":"trace[1539137283] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"228.162405ms","start":"2026-04-20T12:01:01.339017Z","end":"2026-04-20T12:01:01.567179Z","steps":["trace[1539137283] 'process raft request' (duration: 227.806495ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:01.567295Z","caller":"traceutil/trace.go:171","msg":"trace[1550058875] linearizableReadLoop","detail":"{readStateIndex:5283; appliedIndex:5278; }","duration":"140.052351ms","start":"2026-04-20T12:01:01.427217Z","end":"2026-04-20T12:01:01.567269Z","steps":["trace[1550058875] 'read index received' (duration: 56.267923ms)","trace[1550058875] 'applied index is now lower than readState.Index' (duration: 83.783348ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:01:01.567322Z","caller":"traceutil/trace.go:171","msg":"trace[1637094493] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"228.099013ms","start":"2026-04-20T12:01:01.339203Z","end":"2026-04-20T12:01:01.567302Z","steps":["trace[1637094493] 'process raft request' (duration: 227.892097ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:01.567418Z","caller":"traceutil/trace.go:171","msg":"trace[995288952] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"141.411677ms","start":"2026-04-20T12:01:01.425993Z","end":"2026-04-20T12:01:01.567405Z","steps":["trace[995288952] 'process raft request' (duration: 141.212971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:01:01.567521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.292627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-20T12:01:01.567564Z","caller":"traceutil/trace.go:171","msg":"trace[1356580436] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5090; }","duration":"140.39809ms","start":"2026-04-20T12:01:01.427155Z","end":"2026-04-20T12:01:01.567554Z","steps":["trace[1356580436] 'agreement among raft nodes before linearized reading' (duration: 140.209785ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:11.810259Z","caller":"traceutil/trace.go:171","msg":"trace[1219296336] linearizableReadLoop","detail":"{readStateIndex:5464; appliedIndex:5463; }","duration":"124.652319ms","start":"2026-04-20T12:01:11.685583Z","end":"2026-04-20T12:01:11.810235Z","steps":["trace[1219296336] 'read index received' (duration: 79.00438ms)","trace[1219296336] 'applied index is now lower than readState.Index' (duration: 45.646929ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:01:11.810315Z","caller":"traceutil/trace.go:171","msg":"trace[456535653] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"145.813625ms","start":"2026-04-20T12:01:11.664464Z","end":"2026-04-20T12:01:11.810278Z","steps":["trace[456535653] 'process raft request' (duration: 100.096843ms)","trace[456535653] 'compare' (duration: 45.538187ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:01:11.810496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.896316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T12:01:11.81053Z","caller":"traceutil/trace.go:171","msg":"trace[361747040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5268; }","duration":"124.984878ms","start":"2026-04-20T12:01:11.685537Z","end":"2026-04-20T12:01:11.810522Z","steps":["trace[361747040] 'agreement among raft nodes before linearized reading' (duration: 124.835854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:01:12.431947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.843122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f447a82e-b75e-4aa7-b6a8-d02433806235\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-20T12:01:12.432088Z","caller":"traceutil/trace.go:171","msg":"trace[915014065] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f447a82e-b75e-4aa7-b6a8-d02433806235; range_end:; response_count:1; response_revision:5276; }","duration":"132.066137ms","start":"2026-04-20T12:01:12.299998Z","end":"2026-04-20T12:01:12.432064Z","steps":["trace[915014065] 'range keys from in-memory index tree' (duration: 131.628246ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:12.432577Z","caller":"traceutil/trace.go:171","msg":"trace[50481973] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"116.019819ms","start":"2026-04-20T12:01:12.316503Z","end":"2026-04-20T12:01:12.432523Z","steps":["trace[50481973] 'process raft request' (duration: 80.082089ms)","trace[50481973] 'compare' (duration: 35.153949ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:01:29.835408Z","caller":"traceutil/trace.go:171","msg":"trace[1230780077] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"120.547ms","start":"2026-04-20T12:01:29.714835Z","end":"2026-04-20T12:01:29.835382Z","steps":["trace[1230780077] 'process raft request' (duration: 120.357505ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:30.04897Z","caller":"traceutil/trace.go:171","msg":"trace[1393295996] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"135.431998ms","start":"2026-04-20T12:01:29.913514Z","end":"2026-04-20T12:01:30.048946Z","steps":["trace[1393295996] 'process raft request' (duration: 89.367127ms)","trace[1393295996] 'compare' (duration: 45.67014ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:01:31.11964Z","caller":"traceutil/trace.go:171","msg":"trace[1120964661] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"425.451744ms","start":"2026-04-20T12:01:30.694148Z","end":"2026-04-20T12:01:31.1196Z","steps":["trace[1120964661] 'process raft request' (duration: 358.383923ms)","trace[1120964661] 'compare' (duration: 66.789373ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:01:31.119886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:01:30.694122Z","time spent":"425.633309ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:02:42.479112Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3020} {"level":"info","ts":"2026-04-20T12:02:42.567112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3020,"took":"86.450875ms","hash":245670402,"current-db-size-bytes":58638336,"current-db-size":"59 MB","current-db-size-in-use-bytes":31846400,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-20T12:02:42.567242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":245670402,"revision":3020,"compact-revision":-1} {"level":"warn","ts":"2026-04-20T12:07:14.697224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.901392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:07:14.697325Z","caller":"traceutil/trace.go:171","msg":"trace[483385377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8153; }","duration":"541.071787ms","start":"2026-04-20T12:07:14.156232Z","end":"2026-04-20T12:07:14.697304Z","steps":["trace[483385377] 'range keys from in-memory index tree' (duration: 540.779429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:14.697374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:07:14.1562Z","time spent":"541.158619ms","remote":"127.0.0.1:51186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T12:07:14.697673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.884683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:07:14.697792Z","caller":"traceutil/trace.go:171","msg":"trace[1983525223] transaction","detail":"{read_only:false; response_revision:8154; number_of_response:1; }","duration":"318.034499ms","start":"2026-04-20T12:07:14.379736Z","end":"2026-04-20T12:07:14.69777Z","steps":["trace[1983525223] 'process raft request' (duration: 173.900659ms)","trace[1983525223] 'compare' (duration: 143.77052ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:07:14.697864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:07:14.379712Z","time spent":"318.120581ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:07:14.714158Z","caller":"traceutil/trace.go:171","msg":"trace[1534005829] transaction","detail":"{read_only:false; response_revision:8155; number_of_response:1; }","duration":"299.431419ms","start":"2026-04-20T12:07:14.414703Z","end":"2026-04-20T12:07:14.714134Z","steps":["trace[1534005829] 'process raft request' (duration: 299.288865ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:14.714782Z","caller":"traceutil/trace.go:171","msg":"trace[1703514166] transaction","detail":"{read_only:false; response_revision:8156; number_of_response:1; }","duration":"139.271339ms","start":"2026-04-20T12:07:14.575487Z","end":"2026-04-20T12:07:14.714758Z","steps":["trace[1703514166] 'process raft request' (duration: 139.182316ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:25.313485Z","caller":"traceutil/trace.go:171","msg":"trace[920439024] transaction","detail":"{read_only:false; response_revision:8209; number_of_response:1; }","duration":"150.278195ms","start":"2026-04-20T12:07:25.163187Z","end":"2026-04-20T12:07:25.313465Z","steps":["trace[920439024] 'process raft request' (duration: 129.256511ms)","trace[920439024] 'compare' (duration: 20.763257ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:07:25.335267Z","caller":"traceutil/trace.go:171","msg":"trace[2133258722] transaction","detail":"{read_only:false; response_revision:8210; number_of_response:1; }","duration":"123.419873ms","start":"2026-04-20T12:07:25.211807Z","end":"2026-04-20T12:07:25.335227Z","steps":["trace[2133258722] 'process raft request' (duration: 123.132146ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:25.471808Z","caller":"traceutil/trace.go:171","msg":"trace[1123443931] transaction","detail":"{read_only:false; response_revision:8211; number_of_response:1; }","duration":"131.776587ms","start":"2026-04-20T12:07:25.340007Z","end":"2026-04-20T12:07:25.471784Z","steps":["trace[1123443931] 'process raft request' (duration: 122.890759ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:26.352235Z","caller":"traceutil/trace.go:171","msg":"trace[1639146577] linearizableReadLoop","detail":"{readStateIndex:8499; appliedIndex:8498; }","duration":"273.251036ms","start":"2026-04-20T12:07:26.078959Z","end":"2026-04-20T12:07:26.35221Z","steps":["trace[1639146577] 'read index received' (duration: 273.054411ms)","trace[1639146577] 'applied index is now lower than readState.Index' (duration: 195.775µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:07:26.352283Z","caller":"traceutil/trace.go:171","msg":"trace[306070567] transaction","detail":"{read_only:false; response_revision:8212; number_of_response:1; }","duration":"455.965101ms","start":"2026-04-20T12:07:25.896291Z","end":"2026-04-20T12:07:26.352256Z","steps":["trace[306070567] 'process raft request' (duration: 455.774646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:26.352405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:07:25.896262Z","time spent":"456.075015ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:07:26.352472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.490762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T12:07:26.352515Z","caller":"traceutil/trace.go:171","msg":"trace[1241504021] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:8212; }","duration":"273.597765ms","start":"2026-04-20T12:07:26.078907Z","end":"2026-04-20T12:07:26.352505Z","steps":["trace[1241504021] 'agreement among raft nodes before linearized reading' (duration: 273.430401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:26.352595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.420171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-04-20T12:07:26.352631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.998887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-20T12:07:26.352682Z","caller":"traceutil/trace.go:171","msg":"trace[1433854178] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8212; }","duration":"273.569044ms","start":"2026-04-20T12:07:26.079094Z","end":"2026-04-20T12:07:26.352663Z","steps":["trace[1433854178] 'agreement among raft nodes before linearized reading' (duration: 273.307237ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:26.352746Z","caller":"traceutil/trace.go:171","msg":"trace[1407087353] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8212; }","duration":"261.156031ms","start":"2026-04-20T12:07:26.091548Z","end":"2026-04-20T12:07:26.352704Z","steps":["trace[1407087353] 'agreement among raft nodes before linearized reading' (duration: 260.864754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:26.352671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.965092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T12:07:26.352734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.71428ms","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-04-20T12:07:26.352807Z","caller":"traceutil/trace.go:171","msg":"trace[1939244169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8212; }","duration":"240.122776ms","start":"2026-04-20T12:07:26.112658Z","end":"2026-04-20T12:07:26.352781Z","steps":["trace[1939244169] 'agreement among raft nodes before linearized reading' (duration: 239.835799ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:26.352816Z","caller":"traceutil/trace.go:171","msg":"trace[1647364430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8212; }","duration":"266.825514ms","start":"2026-04-20T12:07:26.08597Z","end":"2026-04-20T12:07:26.352795Z","steps":["trace[1647364430] 'agreement among raft nodes before linearized reading' (duration: 266.464124ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:26.931357Z","caller":"traceutil/trace.go:171","msg":"trace[1755033689] transaction","detail":"{read_only:false; response_revision:8218; number_of_response:1; }","duration":"170.846747ms","start":"2026-04-20T12:07:26.760484Z","end":"2026-04-20T12:07:26.931331Z","steps":["trace[1755033689] 'process raft request' (duration: 170.699873ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:26.945137Z","caller":"traceutil/trace.go:171","msg":"trace[127853733] transaction","detail":"{read_only:false; response_revision:8219; number_of_response:1; }","duration":"184.606086ms","start":"2026-04-20T12:07:26.760509Z","end":"2026-04-20T12:07:26.945115Z","steps":["trace[127853733] 'process raft request' (duration: 184.448032ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:27.303133Z","caller":"traceutil/trace.go:171","msg":"trace[878992686] linearizableReadLoop","detail":"{readStateIndex:8508; appliedIndex:8507; }","duration":"146.844722ms","start":"2026-04-20T12:07:27.156192Z","end":"2026-04-20T12:07:27.303036Z","steps":["trace[878992686] 'read index received' (duration: 146.619926ms)","trace[878992686] 'applied index is now lower than readState.Index' (duration: 223.656µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:07:27.303246Z","caller":"traceutil/trace.go:171","msg":"trace[1756607087] transaction","detail":"{read_only:false; response_revision:8220; number_of_response:1; }","duration":"196.597248ms","start":"2026-04-20T12:07:27.106623Z","end":"2026-04-20T12:07:27.30322Z","steps":["trace[1756607087] 'process raft request' (duration: 196.258609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:27.303279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.073178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:07:27.303316Z","caller":"traceutil/trace.go:171","msg":"trace[110022281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8220; }","duration":"147.166492ms","start":"2026-04-20T12:07:27.156138Z","end":"2026-04-20T12:07:27.303304Z","steps":["trace[110022281] 'agreement among raft nodes before linearized reading' (duration: 147.086489ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:42.486022Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6137} {"level":"info","ts":"2026-04-20T12:07:42.585695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6137,"took":"97.639421ms","hash":315187417,"current-db-size-bytes":58638336,"current-db-size":"59 MB","current-db-size-in-use-bytes":23031808,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-20T12:07:42.585771Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":315187417,"revision":6137,"compact-revision":3020} {"level":"info","ts":"2026-04-20T12:07:55.005447Z","caller":"traceutil/trace.go:171","msg":"trace[1228852659] linearizableReadLoop","detail":"{readStateIndex:8715; appliedIndex:8714; }","duration":"396.737051ms","start":"2026-04-20T12:07:54.608663Z","end":"2026-04-20T12:07:55.0054Z","steps":["trace[1228852659] 'read index received' (duration: 332.480226ms)","trace[1228852659] 'applied index is now lower than readState.Index' (duration: 64.255805ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:07:55.005465Z","caller":"traceutil/trace.go:171","msg":"trace[1273976809] transaction","detail":"{read_only:false; response_revision:8418; number_of_response:1; }","duration":"406.324608ms","start":"2026-04-20T12:07:54.599088Z","end":"2026-04-20T12:07:55.005413Z","steps":["trace[1273976809] 'process raft request' (duration: 342.003582ms)","trace[1273976809] 'compare' (duration: 64.002918ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:07:55.005631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.938767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-20T12:07:55.005699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:07:54.599065Z","time spent":"406.506864ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:07:55.005709Z","caller":"traceutil/trace.go:171","msg":"trace[551253582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8418; }","duration":"397.06147ms","start":"2026-04-20T12:07:54.608632Z","end":"2026-04-20T12:07:55.005694Z","steps":["trace[551253582] 'agreement among raft nodes before linearized reading' (duration: 396.849444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:55.005788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:07:54.608611Z","time spent":"397.170773ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T12:07:55.005851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.283035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-04-20T12:07:55.005906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.960297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T12:07:55.00593Z","caller":"traceutil/trace.go:171","msg":"trace[781920784] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8418; }","duration":"226.403928ms","start":"2026-04-20T12:07:54.779507Z","end":"2026-04-20T12:07:55.005911Z","steps":["trace[781920784] 'agreement among raft nodes before linearized reading' (duration: 226.144951ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:55.006102Z","caller":"traceutil/trace.go:171","msg":"trace[2137955940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8418; }","duration":"397.210345ms","start":"2026-04-20T12:07:54.608878Z","end":"2026-04-20T12:07:55.006089Z","steps":["trace[2137955940] 'agreement among raft nodes before linearized reading' (duration: 396.748342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:55.006155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:07:54.608875Z","time spent":"397.267946ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T12:07:55.006103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.858705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-20T12:07:55.006331Z","caller":"traceutil/trace.go:171","msg":"trace[1655826971] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8418; }","duration":"397.097591ms","start":"2026-04-20T12:07:54.6092Z","end":"2026-04-20T12:07:55.006298Z","steps":["trace[1655826971] 'agreement among raft nodes before linearized reading' (duration: 396.629539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:55.006455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:07:54.609196Z","time spent":"397.239495ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-20T12:07:55.214936Z","caller":"traceutil/trace.go:171","msg":"trace[1460837367] transaction","detail":"{read_only:false; response_revision:8419; number_of_response:1; }","duration":"203.993307ms","start":"2026-04-20T12:07:55.010906Z","end":"2026-04-20T12:07:55.214899Z","steps":["trace[1460837367] 'process raft request' (duration: 185.681325ms)","trace[1460837367] 'compare' (duration: 17.783258ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:07:55.218698Z","caller":"traceutil/trace.go:171","msg":"trace[2095689691] linearizableReadLoop","detail":"{readStateIndex:8718; appliedIndex:8715; }","duration":"207.46994ms","start":"2026-04-20T12:07:55.011208Z","end":"2026-04-20T12:07:55.218678Z","steps":["trace[2095689691] 'read index received' (duration: 185.454139ms)","trace[2095689691] 'applied index is now lower than readState.Index' (duration: 22.014831ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:07:55.218702Z","caller":"traceutil/trace.go:171","msg":"trace[1711098136] transaction","detail":"{read_only:false; response_revision:8420; number_of_response:1; }","duration":"207.715336ms","start":"2026-04-20T12:07:55.010959Z","end":"2026-04-20T12:07:55.218674Z","steps":["trace[1711098136] 'process raft request' (duration: 207.517461ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:55.218927Z","caller":"traceutil/trace.go:171","msg":"trace[425672593] transaction","detail":"{read_only:false; response_revision:8423; number_of_response:1; }","duration":"181.461632ms","start":"2026-04-20T12:07:55.037401Z","end":"2026-04-20T12:07:55.218862Z","steps":["trace[425672593] 'process raft request' (duration: 181.243816ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:55.21885Z","caller":"traceutil/trace.go:171","msg":"trace[2105021528] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"207.698166ms","start":"2026-04-20T12:07:55.011139Z","end":"2026-04-20T12:07:55.218837Z","steps":["trace[2105021528] 'process raft request' (duration: 207.437249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:55.219202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.948623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19408"} {"level":"info","ts":"2026-04-20T12:07:55.219273Z","caller":"traceutil/trace.go:171","msg":"trace[1802423409] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8423; }","duration":"208.105577ms","start":"2026-04-20T12:07:55.011156Z","end":"2026-04-20T12:07:55.219262Z","steps":["trace[1802423409] 'agreement among raft nodes before linearized reading' (duration: 207.817439ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:55.219776Z","caller":"traceutil/trace.go:171","msg":"trace[1392773797] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"188.867341ms","start":"2026-04-20T12:07:55.029986Z","end":"2026-04-20T12:07:55.218853Z","steps":["trace[1392773797] 'process raft request' (duration: 188.623964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:55.78534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.564757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-m8d8q\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-20T12:07:55.785433Z","caller":"traceutil/trace.go:171","msg":"trace[273226191] range","detail":"{range_begin:/registry/pods/openstack/keepalived-m8d8q; range_end:; response_count:1; response_revision:8427; }","duration":"107.714191ms","start":"2026-04-20T12:07:55.677697Z","end":"2026-04-20T12:07:55.785412Z","steps":["trace[273226191] 'agreement among raft nodes before linearized reading' (duration: 107.442524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:08:07.734841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.523999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:08:07.735015Z","caller":"traceutil/trace.go:171","msg":"trace[459365246] linearizableReadLoop","detail":"{readStateIndex:8833; appliedIndex:8832; }","duration":"298.969897ms","start":"2026-04-20T12:08:07.435957Z","end":"2026-04-20T12:08:07.734927Z","steps":["trace[459365246] 'read index received' (duration: 33.196081ms)","trace[459365246] 'applied index is now lower than readState.Index' (duration: 265.772596ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:08:07.735295Z","caller":"traceutil/trace.go:171","msg":"trace[26109379] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"300.772525ms","start":"2026-04-20T12:08:07.434464Z","end":"2026-04-20T12:08:07.735237Z","steps":["trace[26109379] 'process raft request' (duration: 34.762793ms)","trace[26109379] 'compare' (duration: 265.297293ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:08:07.735501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:08:07.434454Z","time spent":"300.93992ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:08:07.735735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.389978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-ztrvt\" ","response":"range_response_count:1 size:2802"} {"level":"info","ts":"2026-04-20T12:08:07.736223Z","caller":"traceutil/trace.go:171","msg":"trace[1427917687] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-ztrvt; range_end:; response_count:1; response_revision:8530; }","duration":"300.266792ms","start":"2026-04-20T12:08:07.435932Z","end":"2026-04-20T12:08:07.736199Z","steps":["trace[1427917687] 'agreement among raft nodes before linearized reading' (duration: 299.256925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:08:07.736282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:08:07.435925Z","time spent":"300.341004ms","remote":"127.0.0.1:45232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":2824,"request content":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-ztrvt\" "} {"level":"info","ts":"2026-04-20T12:08:07.925861Z","caller":"traceutil/trace.go:171","msg":"trace[1877217028] linearizableReadLoop","detail":"{readStateIndex:8834; appliedIndex:8833; }","duration":"190.780932ms","start":"2026-04-20T12:08:07.73505Z","end":"2026-04-20T12:08:07.92583Z","steps":["trace[1877217028] 'read index received' (duration: 153.696446ms)","trace[1877217028] 'applied index is now lower than readState.Index' (duration: 37.081066ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:08:07.92588Z","caller":"traceutil/trace.go:171","msg":"trace[221254838] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"488.453114ms","start":"2026-04-20T12:08:07.437402Z","end":"2026-04-20T12:08:07.925855Z","steps":["trace[221254838] 'process raft request' (duration: 451.44229ms)","trace[221254838] 'compare' (duration: 36.777577ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:08:07.926436Z","caller":"traceutil/trace.go:171","msg":"trace[620541416] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8532; }","duration":"187.655608ms","start":"2026-04-20T12:08:07.738443Z","end":"2026-04-20T12:08:07.926099Z","steps":["trace[620541416] 'process raft request' (duration: 187.317079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:08:07.940074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:08:07.43738Z","time spent":"488.65992ms","remote":"127.0.0.1:51404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4630,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:08:07.940384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.070611ms","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-04-20T12:08:07.940458Z","caller":"traceutil/trace.go:171","msg":"trace[833319425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8532; }","duration":"400.199574ms","start":"2026-04-20T12:08:07.540234Z","end":"2026-04-20T12:08:07.940433Z","steps":["trace[833319425] 'agreement among raft nodes before linearized reading' (duration: 385.686654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:08:07.940519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:08:07.540203Z","time spent":"400.303367ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T12:08:07.940581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.355315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-ztrvt\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-04-20T12:08:07.940639Z","caller":"traceutil/trace.go:171","msg":"trace[867839868] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-ztrvt; range_end:; response_count:1; response_revision:8533; }","duration":"176.442747ms","start":"2026-04-20T12:08:07.764179Z","end":"2026-04-20T12:08:07.940622Z","steps":["trace[867839868] 'agreement among raft nodes before linearized reading' (duration: 176.267253ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:08:07.940808Z","caller":"traceutil/trace.go:171","msg":"trace[787990190] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"176.666284ms","start":"2026-04-20T12:08:07.764118Z","end":"2026-04-20T12:08:07.940784Z","steps":["trace[787990190] 'process raft request' (duration: 161.99814ms)","trace[787990190] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/openstack/rook-ceph-detect-version-ztrvt.18a80f4fa62b248c; req_size:743; } (duration: 13.838962ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:08:08.818147Z","caller":"traceutil/trace.go:171","msg":"trace[192512858] linearizableReadLoop","detail":"{readStateIndex:8841; appliedIndex:8840; }","duration":"125.296114ms","start":"2026-04-20T12:08:08.692827Z","end":"2026-04-20T12:08:08.818123Z","steps":["trace[192512858] 'read index received' (duration: 125.058367ms)","trace[192512858] 'applied index is now lower than readState.Index' (duration: 236.757µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:08:08.81821Z","caller":"traceutil/trace.go:171","msg":"trace[552644643] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"288.892236ms","start":"2026-04-20T12:08:08.529295Z","end":"2026-04-20T12:08:08.818187Z","steps":["trace[552644643] 'process raft request' (duration: 288.688031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:08:08.818344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.49843ms","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-04-20T12:08:08.818389Z","caller":"traceutil/trace.go:171","msg":"trace[116061095] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8538; }","duration":"125.591742ms","start":"2026-04-20T12:08:08.692783Z","end":"2026-04-20T12:08:08.818375Z","steps":["trace[116061095] 'agreement among raft nodes before linearized reading' (duration: 125.437658ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:08:50.583455Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000245b.wal"} {"level":"warn","ts":"2026-04-20T12:09:14.095491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.349055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:09:14.095662Z","caller":"traceutil/trace.go:171","msg":"trace[456558994] transaction","detail":"{read_only:false; response_revision:9163; number_of_response:1; }","duration":"416.43808ms","start":"2026-04-20T12:09:13.679197Z","end":"2026-04-20T12:09:14.095635Z","steps":["trace[456558994] 'process raft request' (duration: 277.804778ms)","trace[456558994] 'compare' (duration: 138.083408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:09:14.09575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:13.679179Z","time spent":"416.527662ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:09:14.099452Z","caller":"traceutil/trace.go:171","msg":"trace[758629872] transaction","detail":"{read_only:false; response_revision:9164; number_of_response:1; }","duration":"420.021486ms","start":"2026-04-20T12:09:13.679414Z","end":"2026-04-20T12:09:14.099435Z","steps":["trace[758629872] 'process raft request' (duration: 419.881783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:14.099551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:13.679402Z","time spent":"420.104098ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:09:14.099586Z","caller":"traceutil/trace.go:171","msg":"trace[50316927] linearizableReadLoop","detail":"{readStateIndex:9486; appliedIndex:9483; }","duration":"418.028493ms","start":"2026-04-20T12:09:13.681531Z","end":"2026-04-20T12:09:14.09956Z","steps":["trace[50316927] 'read index received' (duration: 274.987382ms)","trace[50316927] 'applied index is now lower than readState.Index' (duration: 143.039471ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:09:14.099666Z","caller":"traceutil/trace.go:171","msg":"trace[687487697] transaction","detail":"{read_only:false; response_revision:9165; number_of_response:1; }","duration":"420.16068ms","start":"2026-04-20T12:09:13.679463Z","end":"2026-04-20T12:09:14.099623Z","steps":["trace[687487697] 'process raft request' (duration: 419.924194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:14.099792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.2573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T12:09:14.099822Z","caller":"traceutil/trace.go:171","msg":"trace[1789931356] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:9165; }","duration":"418.318031ms","start":"2026-04-20T12:09:13.681496Z","end":"2026-04-20T12:09:14.099814Z","steps":["trace[1789931356] 'agreement among raft nodes before linearized reading' (duration: 418.211208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:14.099822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:13.679434Z","time spent":"420.308695ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:09:14.099841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:13.681487Z","time spent":"418.349701ms","remote":"127.0.0.1:51642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":12,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-04-20T12:09:14.119816Z","caller":"traceutil/trace.go:171","msg":"trace[1558141343] transaction","detail":"{read_only:false; response_revision:9166; number_of_response:1; }","duration":"102.373738ms","start":"2026-04-20T12:09:14.017422Z","end":"2026-04-20T12:09:14.119796Z","steps":["trace[1558141343] 'process raft request' (duration: 102.275236ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:10:08.587248Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T12:10:08.593957Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T12:10:08.594035Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T12:12:42.492559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8305} {"level":"info","ts":"2026-04-20T12:12:42.571632Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8305,"took":"76.692818ms","hash":2848809994,"current-db-size-bytes":58638336,"current-db-size":"59 MB","current-db-size-in-use-bytes":25079808,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-20T12:12:42.571792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2848809994,"revision":8305,"compact-revision":6137} {"level":"info","ts":"2026-04-20T12:17:42.499125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10740} {"level":"info","ts":"2026-04-20T12:17:42.570243Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10740,"took":"69.473942ms","hash":1524368960,"current-db-size-bytes":58638336,"current-db-size":"59 MB","current-db-size-in-use-bytes":20303872,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-20T12:17:42.570299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1524368960,"revision":10740,"compact-revision":8305}