{"level":"warn","ts":"2026-05-11T12:40:29.586424Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T12:40:29.586599Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.112:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.112:2380","--initial-cluster=instance=https://199.19.213.112:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.112:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.112:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-11T12:40:29.586708Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T12:40:29.58675Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.112:2380"]} {"level":"info","ts":"2026-05-11T12:40:29.586792Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T12:40:29.587779Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.112:2379"]} {"level":"info","ts":"2026-05-11T12:40:29.587924Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.112:2380"],"listen-peer-urls":["https://199.19.213.112:2380"],"advertise-client-urls":["https://199.19.213.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.112:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-11T12:40:29.59258Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.326918ms"} {"level":"info","ts":"2026-05-11T12:40:29.599601Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"197930edc5411b2e","cluster-id":"8165613f50a6d530"} {"level":"info","ts":"2026-05-11T12:40:29.599716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T12:40:29.599753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became follower at term 0"} {"level":"info","ts":"2026-05-11T12:40:29.599783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 197930edc5411b2e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T12:40:29.599811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became follower at term 1"} {"level":"info","ts":"2026-05-11T12:40:29.59987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e switched to configuration voters=(1835552120904948526)"} {"level":"warn","ts":"2026-05-11T12:40:29.605995Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T12:40:29.608387Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T12:40:29.611341Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-11T12:40:29.616004Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"197930edc5411b2e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T12:40:29.619443Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"197930edc5411b2e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T12:40:29.619583Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T12:40:29.619638Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T12:40:29.619652Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-11T12:40:29.620876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e switched to configuration voters=(1835552120904948526)"} {"level":"info","ts":"2026-05-11T12:40:29.621125Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8165613f50a6d530","local-member-id":"197930edc5411b2e","added-peer-id":"197930edc5411b2e","added-peer-peer-urls":["https://199.19.213.112:2380"]} {"level":"info","ts":"2026-05-11T12:40:29.623917Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-11T12:40:29.624046Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.112:2380"} {"level":"info","ts":"2026-05-11T12:40:29.624112Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.112:2380"} {"level":"info","ts":"2026-05-11T12:40:29.624243Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"197930edc5411b2e","initial-advertise-peer-urls":["https://199.19.213.112:2380"],"listen-peer-urls":["https://199.19.213.112:2380"],"advertise-client-urls":["https://199.19.213.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T12:40:29.624292Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T12:40:29.70074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T12:40:29.70091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T12:40:29.700979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e received MsgPreVoteResp from 197930edc5411b2e at term 1"} {"level":"info","ts":"2026-05-11T12:40:29.701054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became candidate at term 2"} {"level":"info","ts":"2026-05-11T12:40:29.701088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e received MsgVoteResp from 197930edc5411b2e at term 2"} {"level":"info","ts":"2026-05-11T12:40:29.701143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became leader at term 2"} {"level":"info","ts":"2026-05-11T12:40:29.701162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 197930edc5411b2e elected leader 197930edc5411b2e at term 2"} {"level":"info","ts":"2026-05-11T12:40:29.704653Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T12:40:29.704949Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T12:40:29.704938Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"197930edc5411b2e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.112:2379]}","request-path":"/0/members/197930edc5411b2e/attributes","cluster-id":"8165613f50a6d530","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T12:40:29.705031Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T12:40:29.705459Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T12:40:29.705573Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T12:40:29.708946Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T12:40:29.711058Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.112:2379"} {"level":"info","ts":"2026-05-11T12:40:29.712023Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8165613f50a6d530","local-member-id":"197930edc5411b2e","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T12:40:29.712155Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T12:40:29.712207Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T12:40:38.350457Z","caller":"traceutil/trace.go:171","msg":"trace[1894314020] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"297.228557ms","start":"2026-05-11T12:40:38.053183Z","end":"2026-05-11T12:40:38.350411Z","steps":["trace[1894314020] 'read index received' (duration: 296.997192ms)","trace[1894314020] 'applied index is now lower than readState.Index' (duration: 230.035µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:40:38.350787Z","caller":"traceutil/trace.go:171","msg":"trace[708120727] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"450.639534ms","start":"2026-05-11T12:40:37.899951Z","end":"2026-05-11T12:40:38.350591Z","steps":["trace[708120727] 'process raft request' (duration: 450.281266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:40:38.350865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.656557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-11T12:40:38.350948Z","caller":"traceutil/trace.go:171","msg":"trace[374600576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:329; }","duration":"297.757909ms","start":"2026-05-11T12:40:38.053167Z","end":"2026-05-11T12:40:38.350925Z","steps":["trace[374600576] 'agreement among raft nodes before linearized reading' (duration: 297.400221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:40:38.351127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:40:37.899928Z","time spent":"450.94389ms","remote":"127.0.0.1:57598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T12:40:38.864888Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958676709802811892,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T12:40:39.365623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958676709802811892,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T12:40:39.866097Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958676709802811892,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T12:40:40.367219Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958676709802811892,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T12:40:40.868277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958676709802811892,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-11T12:40:41.021194Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.667747792s","expected-duration":"1s"} {"level":"info","ts":"2026-05-11T12:40:41.022953Z","caller":"traceutil/trace.go:171","msg":"trace[1890731070] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"2.669564902s","start":"2026-05-11T12:40:38.353351Z","end":"2026-05-11T12:40:41.022916Z","steps":["trace[1890731070] 'process raft request' (duration: 2.668092069s)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:41.022926Z","caller":"traceutil/trace.go:171","msg":"trace[956329706] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:341; }","duration":"2.65861004s","start":"2026-05-11T12:40:38.364285Z","end":"2026-05-11T12:40:41.022895Z","steps":["trace[956329706] 'read index received' (duration: 2.657064566s)","trace[956329706] 'applied index is now lower than readState.Index' (duration: 1.544024ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T12:40:41.023035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.658730533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T12:40:41.023643Z","caller":"traceutil/trace.go:171","msg":"trace[1566062772] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:330; }","duration":"2.659348136s","start":"2026-05-11T12:40:38.364254Z","end":"2026-05-11T12:40:41.023602Z","steps":["trace[1566062772] 'agreement among raft nodes before linearized reading' (duration: 2.658727722s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:40:41.023851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:40:38.364238Z","time spent":"2.659597252s","remote":"127.0.0.1:57598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" "} {"level":"warn","ts":"2026-05-11T12:40:41.023907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.380005944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-05-11T12:40:41.023963Z","caller":"traceutil/trace.go:171","msg":"trace[718068221] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:330; }","duration":"2.380098016s","start":"2026-05-11T12:40:38.643852Z","end":"2026-05-11T12:40:41.02395Z","steps":["trace[718068221] 'agreement among raft nodes before linearized reading' (duration: 2.379989594s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:40:41.024005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:40:38.643831Z","time spent":"2.380165387s","remote":"127.0.0.1:57566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4220,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-11T12:40:41.023994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.550550696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T12:40:41.023996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718464585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T12:40:41.024056Z","caller":"traceutil/trace.go:171","msg":"trace[878559459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:330; }","duration":"1.550653117s","start":"2026-05-11T12:40:39.473387Z","end":"2026-05-11T12:40:41.02404Z","steps":["trace[878559459] 'agreement among raft nodes before linearized reading' (duration: 1.550553095s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:40:41.024076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.83756816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-11T12:40:41.024097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:40:39.473369Z","time spent":"1.550721319s","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-11T12:40:41.02411Z","caller":"traceutil/trace.go:171","msg":"trace[1650239988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:330; }","duration":"1.718619739s","start":"2026-05-11T12:40:39.305464Z","end":"2026-05-11T12:40:41.024084Z","steps":["trace[1650239988] 'agreement among raft nodes before linearized reading' (duration: 1.718408234s)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:41.024132Z","caller":"traceutil/trace.go:171","msg":"trace[6796515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:330; }","duration":"1.837669762s","start":"2026-05-11T12:40:39.18645Z","end":"2026-05-11T12:40:41.024119Z","steps":["trace[6796515] 'agreement among raft nodes before linearized reading' (duration: 1.83757091s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:40:41.024166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:40:39.186423Z","time spent":"1.837734114s","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T12:40:41.024175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:40:39.305446Z","time spent":"1.718715321s","remote":"127.0.0.1:57658","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-05-11T12:40:41.024206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.056296622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-11T12:40:41.024237Z","caller":"traceutil/trace.go:171","msg":"trace[1834547386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:330; }","duration":"2.056354892s","start":"2026-05-11T12:40:38.967872Z","end":"2026-05-11T12:40:41.024227Z","steps":["trace[1834547386] 'agreement among raft nodes before linearized reading' (duration: 2.056297601s)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:40:41.024274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:40:38.967857Z","time spent":"2.056411234s","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T12:40:41.024664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:40:38.35333Z","time spent":"2.669776627s","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T12:40:55.519269Z","caller":"traceutil/trace.go:171","msg":"trace[907712055] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"120.339293ms","start":"2026-05-11T12:40:55.398904Z","end":"2026-05-11T12:40:55.519243Z","steps":["trace[907712055] 'process raft request' (duration: 120.268311ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:55.519464Z","caller":"traceutil/trace.go:171","msg":"trace[1384939973] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"159.931306ms","start":"2026-05-11T12:40:55.359482Z","end":"2026-05-11T12:40:55.519413Z","steps":["trace[1384939973] 'process raft request' (duration: 159.441156ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:55.519757Z","caller":"traceutil/trace.go:171","msg":"trace[128280282] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"168.08098ms","start":"2026-05-11T12:40:55.351663Z","end":"2026-05-11T12:40:55.519744Z","steps":["trace[128280282] 'process raft request' (duration: 166.725421ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:55.519831Z","caller":"traceutil/trace.go:171","msg":"trace[897856888] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"151.431225ms","start":"2026-05-11T12:40:55.368379Z","end":"2026-05-11T12:40:55.519811Z","steps":["trace[897856888] 'process raft request' (duration: 150.587127ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:55.519919Z","caller":"traceutil/trace.go:171","msg":"trace[1351034028] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"165.394983ms","start":"2026-05-11T12:40:55.354513Z","end":"2026-05-11T12:40:55.519908Z","steps":["trace[1351034028] 'process raft request' (duration: 164.067344ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:55.520021Z","caller":"traceutil/trace.go:171","msg":"trace[1383889146] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"162.088932ms","start":"2026-05-11T12:40:55.357922Z","end":"2026-05-11T12:40:55.520011Z","steps":["trace[1383889146] 'process raft request' (duration: 160.773824ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:55.52014Z","caller":"traceutil/trace.go:171","msg":"trace[372841143] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"161.97203ms","start":"2026-05-11T12:40:55.358159Z","end":"2026-05-11T12:40:55.520131Z","steps":["trace[372841143] 'process raft request' (duration: 160.640861ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:55.520103Z","caller":"traceutil/trace.go:171","msg":"trace[1304983912] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"151.358794ms","start":"2026-05-11T12:40:55.368732Z","end":"2026-05-11T12:40:55.52009Z","steps":["trace[1304983912] 'process raft request' (duration: 150.27015ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:55.520555Z","caller":"traceutil/trace.go:171","msg":"trace[1586356002] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"142.494354ms","start":"2026-05-11T12:40:55.378049Z","end":"2026-05-11T12:40:55.520544Z","steps":["trace[1586356002] 'process raft request' (duration: 140.994472ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:55.52066Z","caller":"traceutil/trace.go:171","msg":"trace[1269461890] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"141.496434ms","start":"2026-05-11T12:40:55.379154Z","end":"2026-05-11T12:40:55.520651Z","steps":["trace[1269461890] 'process raft request' (duration: 139.949491ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:55.524049Z","caller":"traceutil/trace.go:171","msg":"trace[867677259] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"104.835272ms","start":"2026-05-11T12:40:55.419193Z","end":"2026-05-11T12:40:55.524028Z","steps":["trace[867677259] 'process raft request' (duration: 104.528636ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:55.52408Z","caller":"traceutil/trace.go:171","msg":"trace[436186650] transaction","detail":"{read_only:false; number_of_response:1; response_revision:508; }","duration":"103.691178ms","start":"2026-05-11T12:40:55.420361Z","end":"2026-05-11T12:40:55.524052Z","steps":["trace[436186650] 'process raft request' (duration: 103.623176ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:40:55.524207Z","caller":"traceutil/trace.go:171","msg":"trace[1361168195] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"104.385163ms","start":"2026-05-11T12:40:55.419805Z","end":"2026-05-11T12:40:55.52419Z","steps":["trace[1361168195] 'process raft request' (duration: 104.109377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:41:02.296405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.511097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T12:41:02.296548Z","caller":"traceutil/trace.go:171","msg":"trace[1375214625] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"382.511364ms","start":"2026-05-11T12:41:01.914025Z","end":"2026-05-11T12:41:02.296536Z","steps":["trace[1375214625] 'process raft request' (duration: 382.464494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:41:02.296603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:41:01.914003Z","time spent":"382.567785ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T12:41:02.296669Z","caller":"traceutil/trace.go:171","msg":"trace[1997149659] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"388.331107ms","start":"2026-05-11T12:41:01.908306Z","end":"2026-05-11T12:41:02.296638Z","steps":["trace[1997149659] 'process raft request' (duration: 260.436041ms)","trace[1997149659] 'compare' (duration: 127.280923ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T12:41:02.296769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:41:01.908289Z","time spent":"388.436339ms","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T12:41:16.843557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.895968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T12:41:16.843645Z","caller":"traceutil/trace.go:171","msg":"trace[2141554702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"184.01926ms","start":"2026-05-11T12:41:16.659608Z","end":"2026-05-11T12:41:16.843627Z","steps":["trace[2141554702] 'range keys from in-memory index tree' (duration: 183.825136ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:41:35.858604Z","caller":"traceutil/trace.go:171","msg":"trace[382837121] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"103.719302ms","start":"2026-05-11T12:41:35.754866Z","end":"2026-05-11T12:41:35.858585Z","steps":["trace[382837121] 'process raft request' (duration: 103.613869ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:43:20.007684Z","caller":"traceutil/trace.go:171","msg":"trace[57447144] linearizableReadLoop","detail":"{readStateIndex:1694; appliedIndex:1693; }","duration":"170.292882ms","start":"2026-05-11T12:43:19.837356Z","end":"2026-05-11T12:43:20.007649Z","steps":["trace[57447144] 'read index received' (duration: 170.128919ms)","trace[57447144] 'applied index is now lower than readState.Index' (duration: 162.123µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:43:20.008138Z","caller":"traceutil/trace.go:171","msg":"trace[1649818678] transaction","detail":"{read_only:false; response_revision:1635; number_of_response:1; }","duration":"172.45735ms","start":"2026-05-11T12:43:19.835643Z","end":"2026-05-11T12:43:20.008101Z","steps":["trace[1649818678] 'process raft request' (duration: 171.757345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:43:20.008581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.200662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T12:43:20.00863Z","caller":"traceutil/trace.go:171","msg":"trace[1653225831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1635; }","duration":"171.280935ms","start":"2026-05-11T12:43:19.837332Z","end":"2026-05-11T12:43:20.008613Z","steps":["trace[1653225831] 'agreement among raft nodes before linearized reading' (duration: 171.106261ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:43:24.599369Z","caller":"traceutil/trace.go:171","msg":"trace[841622848] transaction","detail":"{read_only:false; response_revision:1678; number_of_response:1; }","duration":"124.671127ms","start":"2026-05-11T12:43:24.474669Z","end":"2026-05-11T12:43:24.59934Z","steps":["trace[841622848] 'process raft request' (duration: 124.524584ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:43:48.61057Z","caller":"traceutil/trace.go:171","msg":"trace[1219389762] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"105.193404ms","start":"2026-05-11T12:43:48.505342Z","end":"2026-05-11T12:43:48.610536Z","steps":["trace[1219389762] 'process raft request' (duration: 104.935488ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:43:48.879901Z","caller":"traceutil/trace.go:171","msg":"trace[1961197687] linearizableReadLoop","detail":"{readStateIndex:2315; appliedIndex:2314; }","duration":"219.455137ms","start":"2026-05-11T12:43:48.660424Z","end":"2026-05-11T12:43:48.879879Z","steps":["trace[1961197687] 'read index received' (duration: 219.297314ms)","trace[1961197687] 'applied index is now lower than readState.Index' (duration: 156.843µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:43:48.88013Z","caller":"traceutil/trace.go:171","msg":"trace[338233272] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"256.021022ms","start":"2026-05-11T12:43:48.6241Z","end":"2026-05-11T12:43:48.880121Z","steps":["trace[338233272] 'process raft request' (duration: 255.636623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:43:48.8803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.858326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T12:43:48.880337Z","caller":"traceutil/trace.go:171","msg":"trace[1501272500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2217; }","duration":"219.932097ms","start":"2026-05-11T12:43:48.660389Z","end":"2026-05-11T12:43:48.880321Z","steps":["trace[1501272500] 'agreement among raft nodes before linearized reading' (duration: 219.868796ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:43:49.019979Z","caller":"traceutil/trace.go:171","msg":"trace[252654386] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"246.756167ms","start":"2026-05-11T12:43:48.773195Z","end":"2026-05-11T12:43:49.019952Z","steps":["trace[252654386] 'process raft request' (duration: 246.598294ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:43:49.020258Z","caller":"traceutil/trace.go:171","msg":"trace[1048617241] linearizableReadLoop","detail":"{readStateIndex:2316; appliedIndex:2316; }","duration":"140.283785ms","start":"2026-05-11T12:43:48.879959Z","end":"2026-05-11T12:43:49.020243Z","steps":["trace[1048617241] 'read index received' (duration: 140.275395ms)","trace[1048617241] 'applied index is now lower than readState.Index' (duration: 7.49µs)"],"step_count":2} {"level":"warn","ts":"2026-05-11T12:43:49.020632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.801196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T12:43:49.020681Z","caller":"traceutil/trace.go:171","msg":"trace[862667109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2218; }","duration":"235.901089ms","start":"2026-05-11T12:43:48.784767Z","end":"2026-05-11T12:43:49.020668Z","steps":["trace[862667109] 'agreement among raft nodes before linearized reading' (duration: 235.712445ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:43:49.025462Z","caller":"traceutil/trace.go:171","msg":"trace[278542652] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"137.991264ms","start":"2026-05-11T12:43:48.887441Z","end":"2026-05-11T12:43:49.025432Z","steps":["trace[278542652] 'process raft request' (duration: 137.764069ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:43:49.025846Z","caller":"traceutil/trace.go:171","msg":"trace[960133087] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"137.584886ms","start":"2026-05-11T12:43:48.888247Z","end":"2026-05-11T12:43:49.025832Z","steps":["trace[960133087] 'process raft request' (duration: 137.125456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:43:49.025979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.630241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T12:43:49.026088Z","caller":"traceutil/trace.go:171","msg":"trace[782869476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2220; }","duration":"144.752343ms","start":"2026-05-11T12:43:48.881315Z","end":"2026-05-11T12:43:49.026067Z","steps":["trace[782869476] 'agreement among raft nodes before linearized reading' (duration: 144.511958ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:44:07.905306Z","caller":"traceutil/trace.go:171","msg":"trace[68955266] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"259.077727ms","start":"2026-05-11T12:44:07.646203Z","end":"2026-05-11T12:44:07.90528Z","steps":["trace[68955266] 'process raft request' (duration: 258.893673ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:44:19.313912Z","caller":"traceutil/trace.go:171","msg":"trace[1942741732] linearizableReadLoop","detail":"{readStateIndex:2571; appliedIndex:2570; }","duration":"106.743626ms","start":"2026-05-11T12:44:19.207124Z","end":"2026-05-11T12:44:19.313867Z","steps":["trace[1942741732] 'read index received' (duration: 106.568863ms)","trace[1942741732] 'applied index is now lower than readState.Index' (duration: 173.423µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:44:19.313929Z","caller":"traceutil/trace.go:171","msg":"trace[674511766] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"155.186007ms","start":"2026-05-11T12:44:19.158719Z","end":"2026-05-11T12:44:19.313905Z","steps":["trace[674511766] 'process raft request' (duration: 154.953722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:44:19.314117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.973031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-11T12:44:19.31415Z","caller":"traceutil/trace.go:171","msg":"trace[266503895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2465; }","duration":"107.055652ms","start":"2026-05-11T12:44:19.207084Z","end":"2026-05-11T12:44:19.314139Z","steps":["trace[266503895] 'agreement among raft nodes before linearized reading' (duration: 106.907749ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:44:27.452209Z","caller":"traceutil/trace.go:171","msg":"trace[1341150662] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"106.142727ms","start":"2026-05-11T12:44:27.346038Z","end":"2026-05-11T12:44:27.452181Z","steps":["trace[1341150662] 'process raft request' (duration: 105.122115ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:44:27.957569Z","caller":"traceutil/trace.go:171","msg":"trace[1261433781] transaction","detail":"{read_only:false; response_revision:2525; number_of_response:1; }","duration":"136.664357ms","start":"2026-05-11T12:44:27.820874Z","end":"2026-05-11T12:44:27.957538Z","steps":["trace[1261433781] 'process raft request' (duration: 136.391151ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:44:28.187312Z","caller":"traceutil/trace.go:171","msg":"trace[1221603957] linearizableReadLoop","detail":"{readStateIndex:2633; appliedIndex:2632; }","duration":"148.138425ms","start":"2026-05-11T12:44:28.03915Z","end":"2026-05-11T12:44:28.187288Z","steps":["trace[1221603957] 'read index received' (duration: 147.983132ms)","trace[1221603957] 'applied index is now lower than readState.Index' (duration: 154.273µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:44:28.187364Z","caller":"traceutil/trace.go:171","msg":"trace[511375827] transaction","detail":"{read_only:false; response_revision:2526; number_of_response:1; }","duration":"214.613093ms","start":"2026-05-11T12:44:27.972729Z","end":"2026-05-11T12:44:28.187342Z","steps":["trace[511375827] 'process raft request' (duration: 214.399059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:44:28.187644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.309539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T12:44:28.187693Z","caller":"traceutil/trace.go:171","msg":"trace[456319892] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2526; }","duration":"148.595535ms","start":"2026-05-11T12:44:28.039084Z","end":"2026-05-11T12:44:28.18768Z","steps":["trace[456319892] 'agreement among raft nodes before linearized reading' (duration: 148.326819ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:46:12.834438Z","caller":"traceutil/trace.go:171","msg":"trace[799075259] transaction","detail":"{read_only:false; response_revision:3196; number_of_response:1; }","duration":"116.989238ms","start":"2026-05-11T12:46:12.717423Z","end":"2026-05-11T12:46:12.834412Z","steps":["trace[799075259] 'process raft request' (duration: 116.828663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:46:58.796847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.807867ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-11T12:46:58.797227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.102051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T12:46:58.797339Z","caller":"traceutil/trace.go:171","msg":"trace[1361986063] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:3589; }","duration":"142.271986ms","start":"2026-05-11T12:46:58.655047Z","end":"2026-05-11T12:46:58.797319Z","steps":["trace[1361986063] 'agreement among raft nodes before linearized reading' (duration: 142.06489ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:46:58.797658Z","caller":"traceutil/trace.go:171","msg":"trace[564518236] linearizableReadLoop","detail":"{readStateIndex:3730; appliedIndex:3729; }","duration":"141.888665ms","start":"2026-05-11T12:46:58.655072Z","end":"2026-05-11T12:46:58.796961Z","steps":["trace[564518236] 'read index received' (duration: 9.974145ms)","trace[564518236] 'applied index is now lower than readState.Index' (duration: 131.91283ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T12:46:58.817476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.326281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T12:46:58.817565Z","caller":"traceutil/trace.go:171","msg":"trace[2123966798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3590; }","duration":"161.432864ms","start":"2026-05-11T12:46:58.656115Z","end":"2026-05-11T12:46:58.817548Z","steps":["trace[2123966798] 'agreement among raft nodes before linearized reading' (duration: 161.2969ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:46:58.817744Z","caller":"traceutil/trace.go:171","msg":"trace[1156702524] transaction","detail":"{read_only:false; response_revision:3590; number_of_response:1; }","duration":"122.13959ms","start":"2026-05-11T12:46:58.695579Z","end":"2026-05-11T12:46:58.817718Z","steps":["trace[1156702524] 'process raft request' (duration: 121.660487ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:50:30.128743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2873} {"level":"info","ts":"2026-05-11T12:50:30.202882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2873,"took":"72.797046ms","hash":1294407909,"current-db-size-bytes":38084608,"current-db-size":"38 MB","current-db-size-in-use-bytes":14118912,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-05-11T12:50:30.202963Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1294407909,"revision":2873,"compact-revision":-1} {"level":"warn","ts":"2026-05-11T12:51:44.339038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.542283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T12:51:44.339261Z","caller":"traceutil/trace.go:171","msg":"trace[2144229458] transaction","detail":"{read_only:false; response_revision:5608; number_of_response:1; }","duration":"182.979811ms","start":"2026-05-11T12:51:44.156255Z","end":"2026-05-11T12:51:44.339235Z","steps":["trace[2144229458] 'process raft request' (duration: 82.12595ms)","trace[2144229458] 'compare' (duration: 100.412521ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:51:44.522988Z","caller":"traceutil/trace.go:171","msg":"trace[1709687335] transaction","detail":"{read_only:false; response_revision:5610; number_of_response:1; }","duration":"127.340561ms","start":"2026-05-11T12:51:44.395614Z","end":"2026-05-11T12:51:44.522955Z","steps":["trace[1709687335] 'process raft request' (duration: 127.149497ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:52:54.466525Z","caller":"traceutil/trace.go:171","msg":"trace[1560377789] transaction","detail":"{read_only:false; response_revision:6611; number_of_response:1; }","duration":"111.266205ms","start":"2026-05-11T12:52:54.355222Z","end":"2026-05-11T12:52:54.466489Z","steps":["trace[1560377789] 'process raft request' (duration: 111.02797ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:53:00.156695Z","caller":"traceutil/trace.go:171","msg":"trace[2129724872] transaction","detail":"{read_only:false; response_revision:6658; number_of_response:1; }","duration":"195.314297ms","start":"2026-05-11T12:52:59.961338Z","end":"2026-05-11T12:53:00.156652Z","steps":["trace[2129724872] 'process raft request' (duration: 195.075362ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:53:00.285792Z","caller":"traceutil/trace.go:171","msg":"trace[1707420233] linearizableReadLoop","detail":"{readStateIndex:6910; appliedIndex:6908; }","duration":"309.940737ms","start":"2026-05-11T12:52:59.975831Z","end":"2026-05-11T12:53:00.285772Z","steps":["trace[1707420233] 'read index received' (duration: 180.604122ms)","trace[1707420233] 'applied index is now lower than readState.Index' (duration: 129.335785ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T12:53:00.286001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.155212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-11T12:53:00.28605Z","caller":"traceutil/trace.go:171","msg":"trace[863273653] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:6659; }","duration":"310.265655ms","start":"2026-05-11T12:52:59.975768Z","end":"2026-05-11T12:53:00.286034Z","steps":["trace[863273653] 'agreement among raft nodes before linearized reading' (duration: 310.084151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:53:00.286087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:52:59.975742Z","time spent":"310.335196ms","remote":"127.0.0.1:57554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-05-11T12:53:00.286191Z","caller":"traceutil/trace.go:171","msg":"trace[1657547929] transaction","detail":"{read_only:false; response_revision:6659; number_of_response:1; }","duration":"317.784196ms","start":"2026-05-11T12:52:59.968379Z","end":"2026-05-11T12:53:00.286163Z","steps":["trace[1657547929] 'process raft request' (duration: 317.258084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:53:00.286373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:52:59.968338Z","time spent":"317.94519ms","remote":"127.0.0.1:57576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13274,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T12:53:00.468106Z","caller":"traceutil/trace.go:171","msg":"trace[1847138361] transaction","detail":"{read_only:false; response_revision:6661; number_of_response:1; }","duration":"174.758499ms","start":"2026-05-11T12:53:00.293318Z","end":"2026-05-11T12:53:00.468077Z","steps":["trace[1847138361] 'process raft request' (duration: 101.823368ms)","trace[1847138361] 'compare' (duration: 72.654374ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:53:00.468386Z","caller":"traceutil/trace.go:171","msg":"trace[157813845] transaction","detail":"{read_only:false; response_revision:6662; number_of_response:1; }","duration":"174.34676ms","start":"2026-05-11T12:53:00.294007Z","end":"2026-05-11T12:53:00.468354Z","steps":["trace[157813845] 'process raft request' (duration: 173.990362ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:53:00.468439Z","caller":"traceutil/trace.go:171","msg":"trace[1984960417] transaction","detail":"{read_only:false; response_revision:6663; number_of_response:1; }","duration":"172.63849ms","start":"2026-05-11T12:53:00.29577Z","end":"2026-05-11T12:53:00.468408Z","steps":["trace[1984960417] 'process raft request' (duration: 172.442576ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:53:00.468781Z","caller":"traceutil/trace.go:171","msg":"trace[1983649040] linearizableReadLoop","detail":"{readStateIndex:6915; appliedIndex:6911; }","duration":"102.202677ms","start":"2026-05-11T12:53:00.366569Z","end":"2026-05-11T12:53:00.468772Z","steps":["trace[1983649040] 'read index received' (duration: 28.370685ms)","trace[1983649040] 'applied index is now lower than readState.Index' (duration: 73.831301ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:53:00.468792Z","caller":"traceutil/trace.go:171","msg":"trace[1960546117] transaction","detail":"{read_only:false; response_revision:6664; number_of_response:1; }","duration":"169.386367ms","start":"2026-05-11T12:53:00.29939Z","end":"2026-05-11T12:53:00.468776Z","steps":["trace[1960546117] 'process raft request' (duration: 168.966667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:53:00.468959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.381121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-11T12:53:00.468991Z","caller":"traceutil/trace.go:171","msg":"trace[1543818368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6664; }","duration":"102.455252ms","start":"2026-05-11T12:53:00.366528Z","end":"2026-05-11T12:53:00.468984Z","steps":["trace[1543818368] 'agreement among raft nodes before linearized reading' (duration: 102.283918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:53:00.918509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.587679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-11T12:53:00.918767Z","caller":"traceutil/trace.go:171","msg":"trace[1716446947] transaction","detail":"{read_only:false; response_revision:6666; number_of_response:1; }","duration":"393.258894ms","start":"2026-05-11T12:53:00.52548Z","end":"2026-05-11T12:53:00.918738Z","steps":["trace[1716446947] 'process raft request' (duration: 224.321828ms)","trace[1716446947] 'compare' (duration: 168.352513ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T12:53:00.919339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:53:00.525443Z","time spent":"393.755355ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T12:53:00.932744Z","caller":"traceutil/trace.go:171","msg":"trace[1044122519] transaction","detail":"{read_only:false; response_revision:6667; number_of_response:1; }","duration":"399.553467ms","start":"2026-05-11T12:53:00.533168Z","end":"2026-05-11T12:53:00.932722Z","steps":["trace[1044122519] 'process raft request' (duration: 399.111507ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:53:00.932817Z","caller":"traceutil/trace.go:171","msg":"trace[783138216] linearizableReadLoop","detail":"{readStateIndex:6919; appliedIndex:6916; }","duration":"275.972373ms","start":"2026-05-11T12:53:00.656826Z","end":"2026-05-11T12:53:00.932798Z","steps":["trace[783138216] 'read index received' (duration: 93.021978ms)","trace[783138216] 'applied index is now lower than readState.Index' (duration: 182.949285ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T12:53:00.932859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:53:00.533148Z","time spent":"399.656009ms","remote":"127.0.0.1:57476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T12:53:00.93316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.324131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T12:53:00.933201Z","caller":"traceutil/trace.go:171","msg":"trace[834558357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6669; }","duration":"276.391903ms","start":"2026-05-11T12:53:00.656799Z","end":"2026-05-11T12:53:00.933191Z","steps":["trace[834558357] 'agreement among raft nodes before linearized reading' (duration: 276.318331ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:53:00.933346Z","caller":"traceutil/trace.go:171","msg":"trace[694576756] transaction","detail":"{read_only:false; response_revision:6668; number_of_response:1; }","duration":"282.397479ms","start":"2026-05-11T12:53:00.650939Z","end":"2026-05-11T12:53:00.933336Z","steps":["trace[694576756] 'process raft request' (duration: 281.706224ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:53:00.933458Z","caller":"traceutil/trace.go:171","msg":"trace[776232190] transaction","detail":"{read_only:false; response_revision:6669; number_of_response:1; }","duration":"276.467855ms","start":"2026-05-11T12:53:00.656982Z","end":"2026-05-11T12:53:00.93345Z","steps":["trace[776232190] 'process raft request' (duration: 275.761499ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:53:01.114088Z","caller":"traceutil/trace.go:171","msg":"trace[1557725672] linearizableReadLoop","detail":"{readStateIndex:6921; appliedIndex:6920; }","duration":"107.990039ms","start":"2026-05-11T12:53:01.006073Z","end":"2026-05-11T12:53:01.114063Z","steps":["trace[1557725672] 'read index received' (duration: 32.714935ms)","trace[1557725672] 'applied index is now lower than readState.Index' (duration: 75.273884ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:53:01.114342Z","caller":"traceutil/trace.go:171","msg":"trace[1159351467] transaction","detail":"{read_only:false; response_revision:6670; number_of_response:1; }","duration":"166.880819ms","start":"2026-05-11T12:53:00.947423Z","end":"2026-05-11T12:53:01.114304Z","steps":["trace[1159351467] 'process raft request' (duration: 91.35712ms)","trace[1159351467] 'compare' (duration: 75.060229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T12:53:01.114553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.467319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-11T12:53:01.114976Z","caller":"traceutil/trace.go:171","msg":"trace[228708569] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:6670; }","duration":"108.92199ms","start":"2026-05-11T12:53:01.006023Z","end":"2026-05-11T12:53:01.114945Z","steps":["trace[228708569] 'agreement among raft nodes before linearized reading' (duration: 108.384748ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:53:01.329968Z","caller":"traceutil/trace.go:171","msg":"trace[546772531] transaction","detail":"{read_only:false; response_revision:6672; number_of_response:1; }","duration":"208.284252ms","start":"2026-05-11T12:53:01.12166Z","end":"2026-05-11T12:53:01.329944Z","steps":["trace[546772531] 'process raft request' (duration: 202.723315ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:53:01.330369Z","caller":"traceutil/trace.go:171","msg":"trace[528577512] transaction","detail":"{read_only:false; response_revision:6673; number_of_response:1; }","duration":"208.053417ms","start":"2026-05-11T12:53:01.122296Z","end":"2026-05-11T12:53:01.330349Z","steps":["trace[528577512] 'process raft request' (duration: 207.577456ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:53:01.330434Z","caller":"traceutil/trace.go:171","msg":"trace[1642223249] linearizableReadLoop","detail":"{readStateIndex:6925; appliedIndex:6921; }","duration":"207.455473ms","start":"2026-05-11T12:53:01.122969Z","end":"2026-05-11T12:53:01.330424Z","steps":["trace[1642223249] 'read index received' (duration: 6.958438ms)","trace[1642223249] 'applied index is now lower than readState.Index' (duration: 200.496225ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T12:53:01.330574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.641227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-11T12:53:01.33234Z","caller":"traceutil/trace.go:171","msg":"trace[1243534684] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:6674; }","duration":"209.459688ms","start":"2026-05-11T12:53:01.122847Z","end":"2026-05-11T12:53:01.332306Z","steps":["trace[1243534684] 'agreement among raft nodes before linearized reading' (duration: 207.606636ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:53:01.330643Z","caller":"traceutil/trace.go:171","msg":"trace[224768018] transaction","detail":"{read_only:false; response_revision:6674; number_of_response:1; }","duration":"208.076237ms","start":"2026-05-11T12:53:01.122542Z","end":"2026-05-11T12:53:01.330618Z","steps":["trace[224768018] 'process raft request' (duration: 207.625137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:54:58.68697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.000929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:2317"} {"level":"info","ts":"2026-05-11T12:54:58.687057Z","caller":"traceutil/trace.go:171","msg":"trace[1246244524] range","detail":"{range_begin:/registry/configmaps/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:7362; }","duration":"331.118222ms","start":"2026-05-11T12:54:58.35592Z","end":"2026-05-11T12:54:58.687038Z","steps":["trace[1246244524] 'range keys from in-memory index tree' (duration: 330.823572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:54:58.687097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:54:58.355913Z","time spent":"331.174454ms","remote":"127.0.0.1:57518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":2340,"request content":"key:\"/registry/configmaps/openstack/percona-xtradb-haproxy\" "} {"level":"warn","ts":"2026-05-11T12:54:58.687324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.012636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T12:54:58.687384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.08932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/percona-xtradb-pxc\" ","response":"range_response_count:1 size:1025"} {"level":"info","ts":"2026-05-11T12:54:58.687444Z","caller":"traceutil/trace.go:171","msg":"trace[1788043011] range","detail":"{range_begin:/registry/configmaps/openstack/percona-xtradb-pxc; range_end:; response_count:1; response_revision:7362; }","duration":"334.194173ms","start":"2026-05-11T12:54:58.353237Z","end":"2026-05-11T12:54:58.687431Z","steps":["trace[1788043011] 'range keys from in-memory index tree' (duration: 333.943444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:54:58.687471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:54:58.35322Z","time spent":"334.246004ms","remote":"127.0.0.1:57518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1048,"request content":"key:\"/registry/configmaps/openstack/percona-xtradb-pxc\" "} {"level":"info","ts":"2026-05-11T12:54:58.687444Z","caller":"traceutil/trace.go:171","msg":"trace[380423580] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:7362; }","duration":"201.215302ms","start":"2026-05-11T12:54:58.486193Z","end":"2026-05-11T12:54:58.687408Z","steps":["trace[380423580] 'count revisions from in-memory index tree' (duration: 200.951614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:55:26.745461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958676709802831087,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T12:55:26.836476Z","caller":"traceutil/trace.go:171","msg":"trace[1800298717] transaction","detail":"{read_only:false; response_revision:7496; number_of_response:1; }","duration":"592.309441ms","start":"2026-05-11T12:55:26.244134Z","end":"2026-05-11T12:55:26.836444Z","steps":["trace[1800298717] 'process raft request' (duration: 592.089234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:55:26.83683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:55:26.244116Z","time spent":"592.462046ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T12:55:26.853761Z","caller":"traceutil/trace.go:171","msg":"trace[572571345] transaction","detail":"{read_only:false; response_revision:7497; number_of_response:1; }","duration":"609.28112ms","start":"2026-05-11T12:55:26.244455Z","end":"2026-05-11T12:55:26.853737Z","steps":["trace[572571345] 'process raft request' (duration: 608.95635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:55:26.853919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:55:26.244431Z","time spent":"609.406674ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-11T12:55:26.854045Z","caller":"traceutil/trace.go:171","msg":"trace[1772596235] linearizableReadLoop","detail":"{readStateIndex:7782; appliedIndex:7778; }","duration":"608.838266ms","start":"2026-05-11T12:55:26.245183Z","end":"2026-05-11T12:55:26.854021Z","steps":["trace[1772596235] 'read index received' (duration: 591.049121ms)","trace[1772596235] 'applied index is now lower than readState.Index' (duration: 17.788385ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T12:55:26.854089Z","caller":"traceutil/trace.go:171","msg":"trace[1636599230] transaction","detail":"{read_only:false; response_revision:7499; number_of_response:1; }","duration":"551.607787ms","start":"2026-05-11T12:55:26.302457Z","end":"2026-05-11T12:55:26.854065Z","steps":["trace[1636599230] 'process raft request' (duration: 551.444602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:55:26.854209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:55:26.302441Z","time spent":"551.703209ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T12:55:26.854307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.966945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T12:55:26.854341Z","caller":"traceutil/trace.go:171","msg":"trace[2020608183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7499; }","duration":"606.012356ms","start":"2026-05-11T12:55:26.248319Z","end":"2026-05-11T12:55:26.854332Z","steps":["trace[2020608183] 'agreement among raft nodes before linearized reading' (duration: 605.923603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:55:26.854212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.006322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-11T12:55:26.854374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:55:26.248312Z","time spent":"606.053937ms","remote":"127.0.0.1:57658","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":"info","ts":"2026-05-11T12:55:26.85431Z","caller":"traceutil/trace.go:171","msg":"trace[636566507] transaction","detail":"{read_only:false; response_revision:7498; number_of_response:1; }","duration":"601.018777ms","start":"2026-05-11T12:55:26.253196Z","end":"2026-05-11T12:55:26.854215Z","steps":["trace[636566507] 'process raft request' (duration: 600.496421ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T12:55:26.854409Z","caller":"traceutil/trace.go:171","msg":"trace[1450616035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7499; }","duration":"609.240949ms","start":"2026-05-11T12:55:26.245154Z","end":"2026-05-11T12:55:26.854395Z","steps":["trace[1450616035] 'agreement among raft nodes before linearized reading' (duration: 608.998642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:55:26.854485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:55:26.245145Z","time spent":"609.327582ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-11T12:55:26.854512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.464482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-11T12:55:26.854548Z","caller":"traceutil/trace.go:171","msg":"trace[517969459] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:7499; }","duration":"573.517014ms","start":"2026-05-11T12:55:26.281017Z","end":"2026-05-11T12:55:26.854534Z","steps":["trace[517969459] 'agreement among raft nodes before linearized reading' (duration: 573.454632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:55:26.854586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:55:26.281008Z","time spent":"573.569185ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-11T12:55:26.854603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.896174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-11T12:55:26.854638Z","caller":"traceutil/trace.go:171","msg":"trace[218918878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7499; }","duration":"583.944455ms","start":"2026-05-11T12:55:26.270685Z","end":"2026-05-11T12:55:26.854629Z","steps":["trace[218918878] 'agreement among raft nodes before linearized reading' (duration: 583.877823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:55:26.85467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:55:26.270671Z","time spent":"583.993026ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-11T12:55:26.854751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:55:26.253181Z","time spent":"601.312637ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-11T12:55:26.854818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.873838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-11T12:55:26.854845Z","caller":"traceutil/trace.go:171","msg":"trace[980759422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7499; }","duration":"581.92568ms","start":"2026-05-11T12:55:26.272911Z","end":"2026-05-11T12:55:26.854837Z","steps":["trace[980759422] 'agreement among raft nodes before linearized reading' (duration: 581.825217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:55:26.85487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:55:26.272897Z","time spent":"581.967812ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-11T12:55:26.85506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.16953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-11T12:55:26.855094Z","caller":"traceutil/trace.go:171","msg":"trace[1198714578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7499; }","duration":"583.242732ms","start":"2026-05-11T12:55:26.271843Z","end":"2026-05-11T12:55:26.855085Z","steps":["trace[1198714578] 'agreement among raft nodes before linearized reading' (duration: 583.18838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:55:26.855122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:55:26.27182Z","time spent":"583.296504ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-11T12:55:26.855093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.990341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" limit:500 ","response":"range_response_count:22 size:200444"} {"level":"info","ts":"2026-05-11T12:55:26.855212Z","caller":"traceutil/trace.go:171","msg":"trace[1537035896] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:22; response_revision:7499; }","duration":"592.137975ms","start":"2026-05-11T12:55:26.263066Z","end":"2026-05-11T12:55:26.855204Z","steps":["trace[1537035896] 'agreement among raft nodes before linearized reading' (duration: 591.688861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:55:26.855244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:55:26.263058Z","time spent":"592.175886ms","remote":"127.0.0.1:57576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":22,"response size":200467,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" limit:500 "} {"level":"warn","ts":"2026-05-11T12:55:26.8559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.968357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-cainjector-64b59ddb75-4tj8b\" ","response":"range_response_count:1 size:3772"} {"level":"info","ts":"2026-05-11T12:55:26.85605Z","caller":"traceutil/trace.go:171","msg":"trace[1429387412] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-cainjector-64b59ddb75-4tj8b; range_end:; response_count:1; response_revision:7499; }","duration":"601.160562ms","start":"2026-05-11T12:55:26.25487Z","end":"2026-05-11T12:55:26.85603Z","steps":["trace[1429387412] 'agreement among raft nodes before linearized reading' (duration: 600.128339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T12:55:26.856204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T12:55:26.254861Z","time spent":"601.293006ms","remote":"127.0.0.1:57576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":3795,"request content":"key:\"/registry/pods/cert-manager/cert-manager-cainjector-64b59ddb75-4tj8b\" "} {"level":"info","ts":"2026-05-11T12:55:30.135696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4923} {"level":"info","ts":"2026-05-11T12:55:30.191878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4923,"took":"54.96131ms","hash":4093687143,"current-db-size-bytes":38084608,"current-db-size":"38 MB","current-db-size-in-use-bytes":27992064,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-11T12:55:30.191985Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4093687143,"revision":4923,"compact-revision":2873}