{"level":"warn","ts":"2026-04-02T16:47:01.384744Z","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-02T16:47:01.384831Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.145: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.145:2380","--initial-cluster=instance=https://199.19.213.145:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.145:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.145: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-02T16:47:01.384894Z","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-02T16:47:01.384901Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.145:2380"]} {"level":"info","ts":"2026-04-02T16:47:01.384916Z","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-02T16:47:01.38551Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.145:2379"]} {"level":"info","ts":"2026-04-02T16:47:01.385573Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.145:2380"],"listen-peer-urls":["https://199.19.213.145:2380"],"advertise-client-urls":["https://199.19.213.145:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.145:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.145: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-02T16:47:01.389876Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.104526ms"} {"level":"info","ts":"2026-04-02T16:47:01.395288Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"76a9cbc7029401f7","cluster-id":"ebd7ebb015925037"} {"level":"info","ts":"2026-04-02T16:47:01.395369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T16:47:01.39539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became follower at term 0"} {"level":"info","ts":"2026-04-02T16:47:01.39541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 76a9cbc7029401f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T16:47:01.395428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became follower at term 1"} {"level":"info","ts":"2026-04-02T16:47:01.395472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 switched to configuration voters=(8550589423141782007)"} {"level":"warn","ts":"2026-04-02T16:47:01.401346Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T16:47:01.403114Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T16:47:01.404697Z","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-02T16:47:01.409Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"76a9cbc7029401f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T16:47:01.40954Z","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-02T16:47:01.40958Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"76a9cbc7029401f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T16:47:01.409668Z","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-02T16:47:01.409706Z","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-02T16:47:01.410339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 switched to configuration voters=(8550589423141782007)"} {"level":"info","ts":"2026-04-02T16:47:01.410487Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ebd7ebb015925037","local-member-id":"76a9cbc7029401f7","added-peer-id":"76a9cbc7029401f7","added-peer-peer-urls":["https://199.19.213.145:2380"]} {"level":"info","ts":"2026-04-02T16:47:01.417632Z","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-02T16:47:01.417752Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.145:2380"} {"level":"info","ts":"2026-04-02T16:47:01.417824Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.145:2380"} {"level":"info","ts":"2026-04-02T16:47:01.418018Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"76a9cbc7029401f7","initial-advertise-peer-urls":["https://199.19.213.145:2380"],"listen-peer-urls":["https://199.19.213.145:2380"],"advertise-client-urls":["https://199.19.213.145:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.145:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T16:47:01.418067Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T16:47:02.396335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T16:47:02.396395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T16:47:02.396429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 received MsgPreVoteResp from 76a9cbc7029401f7 at term 1"} {"level":"info","ts":"2026-04-02T16:47:02.396444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became candidate at term 2"} {"level":"info","ts":"2026-04-02T16:47:02.396451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 received MsgVoteResp from 76a9cbc7029401f7 at term 2"} {"level":"info","ts":"2026-04-02T16:47:02.396462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became leader at term 2"} {"level":"info","ts":"2026-04-02T16:47:02.396472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 76a9cbc7029401f7 elected leader 76a9cbc7029401f7 at term 2"} {"level":"info","ts":"2026-04-02T16:47:02.397376Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:47:02.398706Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:47:02.398708Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"76a9cbc7029401f7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.145:2379]}","request-path":"/0/members/76a9cbc7029401f7/attributes","cluster-id":"ebd7ebb015925037","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T16:47:02.398768Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:47:02.399042Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T16:47:02.399101Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T16:47:02.399145Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ebd7ebb015925037","local-member-id":"76a9cbc7029401f7","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:47:02.399327Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:47:02.399372Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:47:02.400807Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T16:47:02.402132Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.145:2379"} {"level":"info","ts":"2026-04-02T16:47:19.138499Z","caller":"traceutil/trace.go:171","msg":"trace[111191565] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"381.618398ms","start":"2026-04-02T16:47:18.756857Z","end":"2026-04-02T16:47:19.138476Z","steps":["trace[111191565] 'process raft request' (duration: 381.455103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:47:19.139163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:47:18.756832Z","time spent":"381.788563ms","remote":"127.0.0.1:38092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:47:19.468668Z","caller":"traceutil/trace.go:171","msg":"trace[1984902029] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"193.312495ms","start":"2026-04-02T16:47:19.275335Z","end":"2026-04-02T16:47:19.468647Z","steps":["trace[1984902029] 'read index received' (duration: 193.225673ms)","trace[1984902029] 'applied index is now lower than readState.Index' (duration: 85.702µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:47:19.468795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.442429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-02T16:47:19.468856Z","caller":"traceutil/trace.go:171","msg":"trace[940168851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:372; }","duration":"193.547352ms","start":"2026-04-02T16:47:19.2753Z","end":"2026-04-02T16:47:19.468847Z","steps":["trace[940168851] 'agreement among raft nodes before linearized reading' (duration: 193.419318ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:47:19.468873Z","caller":"traceutil/trace.go:171","msg":"trace[1934179058] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"323.996323ms","start":"2026-04-02T16:47:19.144781Z","end":"2026-04-02T16:47:19.468778Z","steps":["trace[1934179058] 'process raft request' (duration: 323.757266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:47:19.469116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:47:19.144759Z","time spent":"324.23986ms","remote":"127.0.0.1:38108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:47:32.505332Z","caller":"traceutil/trace.go:171","msg":"trace[1020867416] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"111.554726ms","start":"2026-04-02T16:47:32.393757Z","end":"2026-04-02T16:47:32.505311Z","steps":["trace[1020867416] 'process raft request' (duration: 111.462773ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:47:32.50568Z","caller":"traceutil/trace.go:171","msg":"trace[873087625] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"118.386969ms","start":"2026-04-02T16:47:32.387263Z","end":"2026-04-02T16:47:32.50565Z","steps":["trace[873087625] 'process raft request' (duration: 117.770162ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:47:32.505976Z","caller":"traceutil/trace.go:171","msg":"trace[1751235406] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"112.205783ms","start":"2026-04-02T16:47:32.39376Z","end":"2026-04-02T16:47:32.505966Z","steps":["trace[1751235406] 'process raft request' (duration: 111.508734ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:47:32.506002Z","caller":"traceutil/trace.go:171","msg":"trace[668357476] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"112.238425ms","start":"2026-04-02T16:47:32.393741Z","end":"2026-04-02T16:47:32.505979Z","steps":["trace[668357476] 'process raft request' (duration: 111.453063ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:47:32.506006Z","caller":"traceutil/trace.go:171","msg":"trace[2063310761] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"112.309356ms","start":"2026-04-02T16:47:32.393659Z","end":"2026-04-02T16:47:32.505968Z","steps":["trace[2063310761] 'process raft request' (duration: 111.485843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:47:37.966211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.743273ms","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-02T16:47:37.966292Z","caller":"traceutil/trace.go:171","msg":"trace[57674917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"105.875296ms","start":"2026-04-02T16:47:37.860399Z","end":"2026-04-02T16:47:37.966274Z","steps":["trace[57674917] 'range keys from in-memory index tree' (duration: 105.565198ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:47:38.154743Z","caller":"traceutil/trace.go:171","msg":"trace[1310559356] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"219.861682ms","start":"2026-04-02T16:47:37.934861Z","end":"2026-04-02T16:47:38.154722Z","steps":["trace[1310559356] 'process raft request' (duration: 219.39272ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:47:38.160422Z","caller":"traceutil/trace.go:171","msg":"trace[1846639344] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"189.835421ms","start":"2026-04-02T16:47:37.970557Z","end":"2026-04-02T16:47:38.160392Z","steps":["trace[1846639344] 'process raft request' (duration: 189.684177ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:47:38.160521Z","caller":"traceutil/trace.go:171","msg":"trace[2003753476] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"190.254503ms","start":"2026-04-02T16:47:37.970243Z","end":"2026-04-02T16:47:38.160498Z","steps":["trace[2003753476] 'process raft request' (duration: 189.861613ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:47:38.330093Z","caller":"traceutil/trace.go:171","msg":"trace[120498360] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"118.654531ms","start":"2026-04-02T16:47:38.211413Z","end":"2026-04-02T16:47:38.330067Z","steps":["trace[120498360] 'process raft request' (duration: 118.513277ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:47:39.031996Z","caller":"traceutil/trace.go:171","msg":"trace[1760430081] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"137.571162ms","start":"2026-04-02T16:47:38.894402Z","end":"2026-04-02T16:47:39.031973Z","steps":["trace[1760430081] 'process raft request' (duration: 112.525597ms)","trace[1760430081] 'compare' (duration: 24.952163ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:49:06.452701Z","caller":"traceutil/trace.go:171","msg":"trace[308524130] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"545.262521ms","start":"2026-04-02T16:49:05.907414Z","end":"2026-04-02T16:49:06.452677Z","steps":["trace[308524130] 'process raft request' (duration: 545.160288ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:06.452787Z","caller":"traceutil/trace.go:171","msg":"trace[1304471312] linearizableReadLoop","detail":"{readStateIndex:1867; appliedIndex:1864; }","duration":"445.030355ms","start":"2026-04-02T16:49:06.007734Z","end":"2026-04-02T16:49:06.452764Z","steps":["trace[1304471312] 'read index received' (duration: 347.456651ms)","trace[1304471312] 'applied index is now lower than readState.Index' (duration: 97.572224ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:49:06.452804Z","caller":"traceutil/trace.go:171","msg":"trace[1089699938] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"545.248161ms","start":"2026-04-02T16:49:05.907535Z","end":"2026-04-02T16:49:06.452784Z","steps":["trace[1089699938] 'process raft request' (duration: 545.100017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:06.452941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:05.907519Z","time spent":"545.336932ms","remote":"127.0.0.1:37910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:49:06.452953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:05.907393Z","time spent":"545.386964ms","remote":"127.0.0.1:37770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:49:06.452981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.24578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:49:06.453017Z","caller":"traceutil/trace.go:171","msg":"trace[1651920460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1795; }","duration":"445.312532ms","start":"2026-04-02T16:49:06.00769Z","end":"2026-04-02T16:49:06.453003Z","steps":["trace[1651920460] 'agreement among raft nodes before linearized reading' (duration: 445.182569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:06.453058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:06.007675Z","time spent":"445.375654ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T16:49:06.452692Z","caller":"traceutil/trace.go:171","msg":"trace[816274964] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"545.367313ms","start":"2026-04-02T16:49:05.907287Z","end":"2026-04-02T16:49:06.452654Z","steps":["trace[816274964] 'process raft request' (duration: 447.927032ms)","trace[816274964] 'compare' (duration: 97.059001ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:49:06.453194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.05475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-02T16:49:06.45328Z","caller":"traceutil/trace.go:171","msg":"trace[575442639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1795; }","duration":"237.163203ms","start":"2026-04-02T16:49:06.216099Z","end":"2026-04-02T16:49:06.453262Z","steps":["trace[575442639] 'agreement among raft nodes before linearized reading' (duration: 236.841555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:06.453203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:05.907272Z","time spent":"545.863157ms","remote":"127.0.0.1:37910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:49:06.948133Z","caller":"traceutil/trace.go:171","msg":"trace[1005638060] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"315.238451ms","start":"2026-04-02T16:49:06.632864Z","end":"2026-04-02T16:49:06.948103Z","steps":["trace[1005638060] 'process raft request' (duration: 314.07599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:06.948308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:06.632842Z","time spent":"315.376805ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:49:06.948557Z","caller":"traceutil/trace.go:171","msg":"trace[1699278983] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"315.417336ms","start":"2026-04-02T16:49:06.633086Z","end":"2026-04-02T16:49:06.948504Z","steps":["trace[1699278983] 'process raft request' (duration: 314.969874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:06.948761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:06.633052Z","time spent":"315.57408ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:49:06.949134Z","caller":"traceutil/trace.go:171","msg":"trace[200622420] linearizableReadLoop","detail":"{readStateIndex:1872; appliedIndex:1870; }","duration":"264.878761ms","start":"2026-04-02T16:49:06.684237Z","end":"2026-04-02T16:49:06.949116Z","steps":["trace[200622420] 'read index received' (duration: 262.60483ms)","trace[200622420] 'applied index is now lower than readState.Index' (duration: 2.272711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:49:06.949442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.19824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-02T16:49:06.949483Z","caller":"traceutil/trace.go:171","msg":"trace[82269912] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1800; }","duration":"265.267412ms","start":"2026-04-02T16:49:06.684203Z","end":"2026-04-02T16:49:06.94947Z","steps":["trace[82269912] 'agreement among raft nodes before linearized reading' (duration: 264.991384ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:06.956091Z","caller":"traceutil/trace.go:171","msg":"trace[154887386] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"267.012969ms","start":"2026-04-02T16:49:06.689046Z","end":"2026-04-02T16:49:06.956059Z","steps":["trace[154887386] 'process raft request' (duration: 266.870095ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:18.741835Z","caller":"traceutil/trace.go:171","msg":"trace[1957114305] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"193.214176ms","start":"2026-04-02T16:49:18.548584Z","end":"2026-04-02T16:49:18.741798Z","steps":["trace[1957114305] 'process raft request' (duration: 193.045421ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:18.752133Z","caller":"traceutil/trace.go:171","msg":"trace[1376538680] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"201.408298ms","start":"2026-04-02T16:49:18.550705Z","end":"2026-04-02T16:49:18.752113Z","steps":["trace[1376538680] 'process raft request' (duration: 201.239973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:23.030741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.147923ms","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-02T16:49:23.030842Z","caller":"traceutil/trace.go:171","msg":"trace[803379754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1931; }","duration":"207.325688ms","start":"2026-04-02T16:49:22.823495Z","end":"2026-04-02T16:49:23.030821Z","steps":["trace[803379754] 'range keys from in-memory index tree' (duration: 206.909076ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:55.087044Z","caller":"traceutil/trace.go:171","msg":"trace[2043224011] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"171.923443ms","start":"2026-04-02T16:49:54.915092Z","end":"2026-04-02T16:49:55.087016Z","steps":["trace[2043224011] 'process raft request' (duration: 154.902852ms)","trace[2043224011] 'compare' (duration: 16.815936ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:49:55.444855Z","caller":"traceutil/trace.go:171","msg":"trace[1856844134] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2196; }","duration":"109.422828ms","start":"2026-04-02T16:49:55.335407Z","end":"2026-04-02T16:49:55.444829Z","steps":["trace[1856844134] 'read index received' (duration: 19.53934ms)","trace[1856844134] 'applied index is now lower than readState.Index' (duration: 89.882478ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:49:55.444873Z","caller":"traceutil/trace.go:171","msg":"trace[1578237817] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"116.036178ms","start":"2026-04-02T16:49:55.328807Z","end":"2026-04-02T16:49:55.444843Z","steps":["trace[1578237817] 'process raft request' (duration: 26.131459ms)","trace[1578237817] 'compare' (duration: 89.722674ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:49:55.444873Z","caller":"traceutil/trace.go:171","msg":"trace[99141187] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"114.668281ms","start":"2026-04-02T16:49:55.330182Z","end":"2026-04-02T16:49:55.44485Z","steps":["trace[99141187] 'process raft request' (duration: 114.592919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:55.445086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.654914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.145\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T16:49:55.445144Z","caller":"traceutil/trace.go:171","msg":"trace[1662075359] range","detail":"{range_begin:/registry/masterleases/199.19.213.145; range_end:; response_count:1; response_revision:2115; }","duration":"109.767077ms","start":"2026-04-02T16:49:55.335363Z","end":"2026-04-02T16:49:55.44513Z","steps":["trace[1662075359] 'agreement among raft nodes before linearized reading' (duration: 109.577662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:01.542901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.362236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:50:01.543047Z","caller":"traceutil/trace.go:171","msg":"trace[1952465936] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"297.563772ms","start":"2026-04-02T16:50:01.24547Z","end":"2026-04-02T16:50:01.543034Z","steps":["trace[1952465936] 'process raft request' (duration: 297.522261ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:50:01.54325Z","caller":"traceutil/trace.go:171","msg":"trace[560518101] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"431.064734ms","start":"2026-04-02T16:50:01.11208Z","end":"2026-04-02T16:50:01.543145Z","steps":["trace[560518101] 'process raft request' (duration: 105.259936ms)","trace[560518101] 'compare' (duration: 325.272524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:50:01.543622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:01.112062Z","time spent":"431.376782ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:50:01.604757Z","caller":"traceutil/trace.go:171","msg":"trace[224376667] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2225; }","duration":"331.174474ms","start":"2026-04-02T16:50:01.273562Z","end":"2026-04-02T16:50:01.604736Z","steps":["trace[224376667] 'read index received' (duration: 176.296513ms)","trace[224376667] 'applied index is now lower than readState.Index' (duration: 154.876841ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:50:01.605026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.445331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-02T16:50:01.605081Z","caller":"traceutil/trace.go:171","msg":"trace[1781149762] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2143; }","duration":"331.552534ms","start":"2026-04-02T16:50:01.273514Z","end":"2026-04-02T16:50:01.605066Z","steps":["trace[1781149762] 'agreement among raft nodes before linearized reading' (duration: 331.381249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:01.605122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:01.27349Z","time spent":"331.623546ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T16:50:01.605022Z","caller":"traceutil/trace.go:171","msg":"trace[1120868487] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"359.090042ms","start":"2026-04-02T16:50:01.245853Z","end":"2026-04-02T16:50:01.604943Z","steps":["trace[1120868487] 'process raft request' (duration: 358.776713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:01.605374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:01.245839Z","time spent":"359.40272ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:50:01.951187Z","caller":"traceutil/trace.go:171","msg":"trace[945748592] linearizableReadLoop","detail":"{readStateIndex:2231; appliedIndex:2228; }","duration":"346.295604ms","start":"2026-04-02T16:50:01.604861Z","end":"2026-04-02T16:50:01.951156Z","steps":["trace[945748592] 'read index received' (duration: 329.673393ms)","trace[945748592] 'applied index is now lower than readState.Index' (duration: 16.621021ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:50:01.951305Z","caller":"traceutil/trace.go:171","msg":"trace[1571526141] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"417.615289ms","start":"2026-04-02T16:50:01.533661Z","end":"2026-04-02T16:50:01.951277Z","steps":["trace[1571526141] 'process raft request' (duration: 417.445625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:01.951365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.185318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:50:01.951415Z","caller":"traceutil/trace.go:171","msg":"trace[292692262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2146; }","duration":"448.264491ms","start":"2026-04-02T16:50:01.503133Z","end":"2026-04-02T16:50:01.951398Z","steps":["trace[292692262] 'agreement among raft nodes before linearized reading' (duration: 448.164188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:01.951442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:01.503119Z","time spent":"448.317753ms","remote":"127.0.0.1:37574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:50:01.951433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:01.533643Z","time spent":"417.723712ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:50:01.95145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.969691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T16:50:01.951505Z","caller":"traceutil/trace.go:171","msg":"trace[112213089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2146; }","duration":"668.063093ms","start":"2026-04-02T16:50:01.283425Z","end":"2026-04-02T16:50:01.951488Z","steps":["trace[112213089] 'agreement among raft nodes before linearized reading' (duration: 667.870198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:01.95153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.589392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-02T16:50:01.951567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.649776ms","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-02T16:50:01.95163Z","caller":"traceutil/trace.go:171","msg":"trace[2142251938] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2146; }","duration":"614.719986ms","start":"2026-04-02T16:50:01.336885Z","end":"2026-04-02T16:50:01.951605Z","steps":["trace[2142251938] 'agreement among raft nodes before linearized reading' (duration: 614.405948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:01.95168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:01.336875Z","time spent":"614.796738ms","remote":"127.0.0.1:53668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-02T16:50:01.951559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:01.283415Z","time spent":"668.132126ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T16:50:01.951714Z","caller":"traceutil/trace.go:171","msg":"trace[132174530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2146; }","duration":"669.806861ms","start":"2026-04-02T16:50:01.281852Z","end":"2026-04-02T16:50:01.951659Z","steps":["trace[132174530] 'agreement among raft nodes before linearized reading' (duration: 669.516953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:01.951778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:01.28184Z","time spent":"669.923473ms","remote":"127.0.0.1:37898","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":"info","ts":"2026-04-02T16:50:01.951607Z","caller":"traceutil/trace.go:171","msg":"trace[547393435] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"486.724944ms","start":"2026-04-02T16:50:01.464867Z","end":"2026-04-02T16:50:01.951592Z","steps":["trace[547393435] 'process raft request' (duration: 486.170779ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:50:01.951757Z","caller":"traceutil/trace.go:171","msg":"trace[29880093] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"488.982405ms","start":"2026-04-02T16:50:01.462541Z","end":"2026-04-02T16:50:01.951523Z","steps":["trace[29880093] 'process raft request' (duration: 471.981334ms)","trace[29880093] 'compare' (duration: 16.000714ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:50:01.951854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:01.464839Z","time spent":"486.983901ms","remote":"127.0.0.1:37898","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-04-02T16:50:01.952002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:01.462515Z","time spent":"489.328175ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:50:01.955586Z","caller":"traceutil/trace.go:171","msg":"trace[2046199380] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"345.41733ms","start":"2026-04-02T16:50:01.610144Z","end":"2026-04-02T16:50:01.955561Z","steps":["trace[2046199380] 'process raft request' (duration: 345.284527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:01.955705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:01.610123Z","time spent":"345.524054ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:50:01.955845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.462672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:50:01.956003Z","caller":"traceutil/trace.go:171","msg":"trace[80459975] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2147; }","duration":"265.676057ms","start":"2026-04-02T16:50:01.690282Z","end":"2026-04-02T16:50:01.955958Z","steps":["trace[80459975] 'agreement among raft nodes before linearized reading' (duration: 265.337848ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:50:02.063026Z","caller":"traceutil/trace.go:171","msg":"trace[906308078] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"106.555381ms","start":"2026-04-02T16:50:01.9564Z","end":"2026-04-02T16:50:02.062955Z","steps":["trace[906308078] 'process raft request' (duration: 101.971116ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:53:50.353582Z","caller":"traceutil/trace.go:171","msg":"trace[1109545064] transaction","detail":"{read_only:false; response_revision:3752; number_of_response:1; }","duration":"111.848357ms","start":"2026-04-02T16:53:50.241709Z","end":"2026-04-02T16:53:50.353557Z","steps":["trace[1109545064] 'process raft request' (duration: 111.486367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:55:13.90664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.434742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-04-02T16:55:13.906766Z","caller":"traceutil/trace.go:171","msg":"trace[508119520] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4823; }","duration":"101.600677ms","start":"2026-04-02T16:55:13.805138Z","end":"2026-04-02T16:55:13.906739Z","steps":["trace[508119520] 'range keys from in-memory index tree' (duration: 101.243857ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:55:14.766749Z","caller":"traceutil/trace.go:171","msg":"trace[1896482045] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"152.165934ms","start":"2026-04-02T16:55:14.614557Z","end":"2026-04-02T16:55:14.766723Z","steps":["trace[1896482045] 'process raft request' (duration: 151.984759ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:55:14.79134Z","caller":"traceutil/trace.go:171","msg":"trace[1671777089] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"174.503249ms","start":"2026-04-02T16:55:14.616811Z","end":"2026-04-02T16:55:14.791314Z","steps":["trace[1671777089] 'process raft request' (duration: 174.265612ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:55:14.791504Z","caller":"traceutil/trace.go:171","msg":"trace[1656532044] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"171.821917ms","start":"2026-04-02T16:55:14.619663Z","end":"2026-04-02T16:55:14.791485Z","steps":["trace[1656532044] 'process raft request' (duration: 171.55765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:55:18.383844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.160456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:55:18.383931Z","caller":"traceutil/trace.go:171","msg":"trace[1067651457] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:4975; }","duration":"312.30244ms","start":"2026-04-02T16:55:18.071611Z","end":"2026-04-02T16:55:18.383913Z","steps":["trace[1067651457] 'count revisions from in-memory index tree' (duration: 312.031873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:55:18.383968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:55:18.071594Z","time spent":"312.365611ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-02T16:55:18.383988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.783625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eeb52920-c3c2-4e5d-8028-1963688a3d2d\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-02T16:55:18.384086Z","caller":"traceutil/trace.go:171","msg":"trace[20284629] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eeb52920-c3c2-4e5d-8028-1963688a3d2d; range_end:; response_count:1; response_revision:4975; }","duration":"236.647125ms","start":"2026-04-02T16:55:18.14741Z","end":"2026-04-02T16:55:18.384058Z","steps":["trace[20284629] 'range keys from in-memory index tree' (duration: 225.562109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:55:18.384445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.12637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777477"} {"level":"info","ts":"2026-04-02T16:55:18.384534Z","caller":"traceutil/trace.go:171","msg":"trace[407090627] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4975; }","duration":"367.282765ms","start":"2026-04-02T16:55:18.017229Z","end":"2026-04-02T16:55:18.384511Z","steps":["trace[407090627] 'range keys from in-memory index tree' (duration: 366.595727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:55:18.384593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:55:18.0172Z","time spent":"367.379947ms","remote":"127.0.0.1:37698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":777500,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-04-02T16:55:18.759821Z","caller":"traceutil/trace.go:171","msg":"trace[776650398] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"127.525518ms","start":"2026-04-02T16:55:18.632267Z","end":"2026-04-02T16:55:18.759792Z","steps":["trace[776650398] 'process raft request' (duration: 70.090068ms)","trace[776650398] 'compare' (duration: 57.045239ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:55:18.759939Z","caller":"traceutil/trace.go:171","msg":"trace[1786862413] linearizableReadLoop","detail":"{readStateIndex:5167; appliedIndex:5165; }","duration":"108.454779ms","start":"2026-04-02T16:55:18.651468Z","end":"2026-04-02T16:55:18.759923Z","steps":["trace[1786862413] 'read index received' (duration: 8.63169ms)","trace[1786862413] 'applied index is now lower than readState.Index' (duration: 99.822329ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:55:18.760084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.610084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eeb52920-c3c2-4e5d-8028-1963688a3d2d\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-02T16:55:18.760103Z","caller":"traceutil/trace.go:171","msg":"trace[1715290865] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eeb52920-c3c2-4e5d-8028-1963688a3d2d; range_end:; response_count:1; response_revision:4978; }","duration":"108.678005ms","start":"2026-04-02T16:55:18.651419Z","end":"2026-04-02T16:55:18.760097Z","steps":["trace[1715290865] 'agreement among raft nodes before linearized reading' (duration: 108.564912ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:55:27.31564Z","caller":"traceutil/trace.go:171","msg":"trace[827088360] linearizableReadLoop","detail":"{readStateIndex:5336; appliedIndex:5334; }","duration":"111.731107ms","start":"2026-04-02T16:55:27.203872Z","end":"2026-04-02T16:55:27.315604Z","steps":["trace[827088360] 'read index received' (duration: 10.911471ms)","trace[827088360] 'applied index is now lower than readState.Index' (duration: 100.818016ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:55:27.3158Z","caller":"traceutil/trace.go:171","msg":"trace[910263535] transaction","detail":"{read_only:false; response_revision:5140; number_of_response:1; }","duration":"144.191691ms","start":"2026-04-02T16:55:27.171575Z","end":"2026-04-02T16:55:27.315766Z","steps":["trace[910263535] 'process raft request' (duration: 136.203668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:55:27.31596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.063675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:55:27.316024Z","caller":"traceutil/trace.go:171","msg":"trace[1292906064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5140; }","duration":"112.20766ms","start":"2026-04-02T16:55:27.203803Z","end":"2026-04-02T16:55:27.316011Z","steps":["trace[1292906064] 'agreement among raft nodes before linearized reading' (duration: 111.975053ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:55:29.105214Z","caller":"traceutil/trace.go:171","msg":"trace[1373395083] linearizableReadLoop","detail":"{readStateIndex:5383; appliedIndex:5382; }","duration":"135.538131ms","start":"2026-04-02T16:55:28.96965Z","end":"2026-04-02T16:55:29.105188Z","steps":["trace[1373395083] 'read index received' (duration: 96.969433ms)","trace[1373395083] 'applied index is now lower than readState.Index' (duration: 38.567968ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:55:29.105278Z","caller":"traceutil/trace.go:171","msg":"trace[216791386] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"137.555215ms","start":"2026-04-02T16:55:28.967666Z","end":"2026-04-02T16:55:29.105221Z","steps":["trace[216791386] 'process raft request' (duration: 98.969207ms)","trace[216791386] 'compare' (duration: 38.380162ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:55:29.105536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.880711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70804"} {"level":"info","ts":"2026-04-02T16:55:29.105614Z","caller":"traceutil/trace.go:171","msg":"trace[1719531608] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5186; }","duration":"136.026244ms","start":"2026-04-02T16:55:28.96957Z","end":"2026-04-02T16:55:29.105597Z","steps":["trace[1719531608] 'agreement among raft nodes before linearized reading' (duration: 135.712856ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:55:29.333666Z","caller":"traceutil/trace.go:171","msg":"trace[2064330717] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"112.933279ms","start":"2026-04-02T16:55:29.220705Z","end":"2026-04-02T16:55:29.333638Z","steps":["trace[2064330717] 'process raft request' (duration: 26.922288ms)","trace[2064330717] 'compare' (duration: 85.667922ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:55:29.723718Z","caller":"traceutil/trace.go:171","msg":"trace[1442666948] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"116.093643ms","start":"2026-04-02T16:55:29.607593Z","end":"2026-04-02T16:55:29.723687Z","steps":["trace[1442666948] 'process raft request' (duration: 115.918458ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:55:29.723738Z","caller":"traceutil/trace.go:171","msg":"trace[1255670553] linearizableReadLoop","detail":"{readStateIndex:5391; appliedIndex:5390; }","duration":"113.70833ms","start":"2026-04-02T16:55:29.609991Z","end":"2026-04-02T16:55:29.7237Z","steps":["trace[1255670553] 'read index received' (duration: 113.664738ms)","trace[1255670553] 'applied index is now lower than readState.Index' (duration: 40.301µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:55:29.72403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.008097ms","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-02T16:55:29.72406Z","caller":"traceutil/trace.go:171","msg":"trace[1778321080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5194; }","duration":"114.090409ms","start":"2026-04-02T16:55:29.609959Z","end":"2026-04-02T16:55:29.724049Z","steps":["trace[1778321080] 'agreement among raft nodes before linearized reading' (duration: 113.861963ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:55:30.022444Z","caller":"traceutil/trace.go:171","msg":"trace[104812831] linearizableReadLoop","detail":"{readStateIndex:5393; appliedIndex:5391; }","duration":"224.84432ms","start":"2026-04-02T16:55:29.797567Z","end":"2026-04-02T16:55:30.022411Z","steps":["trace[104812831] 'read index received' (duration: 162.576212ms)","trace[104812831] 'applied index is now lower than readState.Index' (duration: 62.266628ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:55:30.022635Z","caller":"traceutil/trace.go:171","msg":"trace[1191928324] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"294.096395ms","start":"2026-04-02T16:55:29.728517Z","end":"2026-04-02T16:55:30.022614Z","steps":["trace[1191928324] 'process raft request' (duration: 293.819197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:55:30.022996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.405265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-02T16:55:30.023014Z","caller":"traceutil/trace.go:171","msg":"trace[33347164] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"412.465328ms","start":"2026-04-02T16:55:29.610509Z","end":"2026-04-02T16:55:30.022975Z","steps":["trace[33347164] 'process raft request' (duration: 349.901722ms)","trace[33347164] 'compare' (duration: 61.729794ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:55:30.023068Z","caller":"traceutil/trace.go:171","msg":"trace[1723966930] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5196; }","duration":"225.536388ms","start":"2026-04-02T16:55:29.797515Z","end":"2026-04-02T16:55:30.023052Z","steps":["trace[1723966930] 'agreement among raft nodes before linearized reading' (duration: 225.278711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:55:30.023205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:55:29.610492Z","time spent":"412.623262ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:55:30.02327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.882938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-8tt82\" ","response":"range_response_count:1 size:5852"} {"level":"info","ts":"2026-04-02T16:55:30.023372Z","caller":"traceutil/trace.go:171","msg":"trace[989500824] range","detail":"{range_begin:/registry/pods/monitoring/vector-8tt82; range_end:; response_count:1; response_revision:5196; }","duration":"220.054092ms","start":"2026-04-02T16:55:29.803296Z","end":"2026-04-02T16:55:30.02335Z","steps":["trace[989500824] 'agreement among raft nodes before linearized reading' (duration: 219.735374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:55:37.608839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.992047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:55:37.60884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.36591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:55:37.608942Z","caller":"traceutil/trace.go:171","msg":"trace[2039214507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5361; }","duration":"105.170231ms","start":"2026-04-02T16:55:37.503749Z","end":"2026-04-02T16:55:37.608919Z","steps":["trace[2039214507] 'range keys from in-memory index tree' (duration: 104.882774ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:55:37.608973Z","caller":"traceutil/trace.go:171","msg":"trace[548398983] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:5361; }","duration":"119.577825ms","start":"2026-04-02T16:55:37.489369Z","end":"2026-04-02T16:55:37.608947Z","steps":["trace[548398983] 'count revisions from in-memory index tree' (duration: 119.273727ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:55:38.278857Z","caller":"traceutil/trace.go:171","msg":"trace[330221566] linearizableReadLoop","detail":"{readStateIndex:5564; appliedIndex:5563; }","duration":"217.005701ms","start":"2026-04-02T16:55:38.061819Z","end":"2026-04-02T16:55:38.278825Z","steps":["trace[330221566] 'read index received' (duration: 216.711023ms)","trace[330221566] 'applied index is now lower than readState.Index' (duration: 292.958µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:55:38.278915Z","caller":"traceutil/trace.go:171","msg":"trace[1941356639] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"370.438998ms","start":"2026-04-02T16:55:37.90844Z","end":"2026-04-02T16:55:38.278879Z","steps":["trace[1941356639] 'process raft request' (duration: 370.142951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:55:38.279133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:55:37.908412Z","time spent":"370.591373ms","remote":"127.0.0.1:37794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:55:38.279171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.33681ms","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-02T16:55:38.279211Z","caller":"traceutil/trace.go:171","msg":"trace[1061584919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5363; }","duration":"217.460313ms","start":"2026-04-02T16:55:38.061739Z","end":"2026-04-02T16:55:38.279199Z","steps":["trace[1061584919] 'agreement among raft nodes before linearized reading' (duration: 217.263808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:55:38.279503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.908685ms","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-02T16:55:38.279639Z","caller":"traceutil/trace.go:171","msg":"trace[1103324345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5363; }","duration":"215.115541ms","start":"2026-04-02T16:55:38.064501Z","end":"2026-04-02T16:55:38.279616Z","steps":["trace[1103324345] 'agreement among raft nodes before linearized reading' (duration: 214.650079ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:55:38.502715Z","caller":"traceutil/trace.go:171","msg":"trace[2109707509] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"218.396938ms","start":"2026-04-02T16:55:38.284289Z","end":"2026-04-02T16:55:38.502686Z","steps":["trace[2109707509] 'process raft request' (duration: 218.187672ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:55:38.504068Z","caller":"traceutil/trace.go:171","msg":"trace[1655775184] linearizableReadLoop","detail":"{readStateIndex:5566; appliedIndex:5564; }","duration":"204.19602ms","start":"2026-04-02T16:55:38.299853Z","end":"2026-04-02T16:55:38.504049Z","steps":["trace[1655775184] 'read index received' (duration: 202.782713ms)","trace[1655775184] 'applied index is now lower than readState.Index' (duration: 1.412297ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:55:38.504199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.324954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:55:38.504161Z","caller":"traceutil/trace.go:171","msg":"trace[2060623587] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"219.55795ms","start":"2026-04-02T16:55:38.284548Z","end":"2026-04-02T16:55:38.504106Z","steps":["trace[2060623587] 'process raft request' (duration: 219.371835ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:55:38.504247Z","caller":"traceutil/trace.go:171","msg":"trace[2117204488] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:5365; }","duration":"204.459047ms","start":"2026-04-02T16:55:38.299775Z","end":"2026-04-02T16:55:38.504234Z","steps":["trace[2117204488] 'agreement among raft nodes before linearized reading' (duration: 204.347704ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:57:02.426649Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2994} {"level":"info","ts":"2026-04-02T16:57:02.507254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2994,"took":"78.870492ms","hash":2766077843,"current-db-size-bytes":58748928,"current-db-size":"59 MB","current-db-size-in-use-bytes":33964032,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-02T16:57:02.507324Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2766077843,"revision":2994,"compact-revision":-1} {"level":"info","ts":"2026-04-02T16:59:54.95688Z","caller":"traceutil/trace.go:171","msg":"trace[1510006007] linearizableReadLoop","detail":"{readStateIndex:7571; appliedIndex:7570; }","duration":"315.950088ms","start":"2026-04-02T16:59:54.640907Z","end":"2026-04-02T16:59:54.956857Z","steps":["trace[1510006007] 'read index received' (duration: 315.717541ms)","trace[1510006007] 'applied index is now lower than readState.Index' (duration: 230.877µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:59:54.957147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.213865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fhklx\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-02T16:59:54.957233Z","caller":"traceutil/trace.go:171","msg":"trace[1874902999] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fhklx; range_end:; response_count:1; response_revision:7308; }","duration":"316.340447ms","start":"2026-04-02T16:59:54.640879Z","end":"2026-04-02T16:59:54.95722Z","steps":["trace[1874902999] 'agreement among raft nodes before linearized reading' (duration: 316.07878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:59:54.957295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:59:54.640867Z","time spent":"316.4177ms","remote":"127.0.0.1:37794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-fhklx\" "} {"level":"info","ts":"2026-04-02T16:59:54.957014Z","caller":"traceutil/trace.go:171","msg":"trace[208018905] transaction","detail":"{read_only:false; response_revision:7308; number_of_response:1; }","duration":"339.369731ms","start":"2026-04-02T16:59:54.617597Z","end":"2026-04-02T16:59:54.956967Z","steps":["trace[208018905] 'process raft request' (duration: 339.085993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:59:54.957689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:59:54.617565Z","time spent":"339.937386ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:59:54.963519Z","caller":"traceutil/trace.go:171","msg":"trace[1541787382] transaction","detail":"{read_only:false; response_revision:7309; number_of_response:1; }","duration":"231.11195ms","start":"2026-04-02T16:59:54.732377Z","end":"2026-04-02T16:59:54.963489Z","steps":["trace[1541787382] 'process raft request' (duration: 230.764481ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:02.433538Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6102} {"level":"info","ts":"2026-04-02T17:02:02.536271Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6102,"took":"100.794093ms","hash":3069735068,"current-db-size-bytes":58748928,"current-db-size":"59 MB","current-db-size-in-use-bytes":21667840,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-02T17:02:02.536354Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3069735068,"revision":6102,"compact-revision":2994} {"level":"info","ts":"2026-04-02T17:02:08.842453Z","caller":"traceutil/trace.go:171","msg":"trace[1832818252] linearizableReadLoop","detail":"{readStateIndex:8611; appliedIndex:8610; }","duration":"341.976133ms","start":"2026-04-02T17:02:08.500451Z","end":"2026-04-02T17:02:08.842427Z","steps":["trace[1832818252] 'read index received' (duration: 341.812979ms)","trace[1832818252] 'applied index is now lower than readState.Index' (duration: 161.844µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:02:08.842644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.141887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:02:08.842784Z","caller":"traceutil/trace.go:171","msg":"trace[1554994744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8315; }","duration":"342.337183ms","start":"2026-04-02T17:02:08.500416Z","end":"2026-04-02T17:02:08.842753Z","steps":["trace[1554994744] 'agreement among raft nodes before linearized reading' (duration: 342.093056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:08.842876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:08.500399Z","time spent":"342.466416ms","remote":"127.0.0.1:37574","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-04-02T17:02:08.843271Z","caller":"traceutil/trace.go:171","msg":"trace[336163692] transaction","detail":"{read_only:false; response_revision:8315; number_of_response:1; }","duration":"665.766712ms","start":"2026-04-02T17:02:08.177482Z","end":"2026-04-02T17:02:08.843249Z","steps":["trace[336163692] 'process raft request' (duration: 664.750805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:08.843529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:08.177462Z","time spent":"665.965458ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:02:09.311559Z","caller":"traceutil/trace.go:171","msg":"trace[811429592] transaction","detail":"{read_only:false; response_revision:8316; number_of_response:1; }","duration":"766.664839ms","start":"2026-04-02T17:02:08.544862Z","end":"2026-04-02T17:02:09.311526Z","steps":["trace[811429592] 'process raft request' (duration: 732.191751ms)","trace[811429592] 'compare' (duration: 34.115708ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:09.31171Z","caller":"traceutil/trace.go:171","msg":"trace[1199101169] linearizableReadLoop","detail":"{readStateIndex:8613; appliedIndex:8611; }","duration":"469.144609ms","start":"2026-04-02T17:02:08.842533Z","end":"2026-04-02T17:02:09.311678Z","steps":["trace[1199101169] 'read index received' (duration: 434.713652ms)","trace[1199101169] 'applied index is now lower than readState.Index' (duration: 34.429727ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:09.311839Z","caller":"traceutil/trace.go:171","msg":"trace[167410465] transaction","detail":"{read_only:false; response_revision:8317; number_of_response:1; }","duration":"758.656396ms","start":"2026-04-02T17:02:08.553168Z","end":"2026-04-02T17:02:09.311824Z","steps":["trace[167410465] 'process raft request' (duration: 758.303946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:09.311954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:08.553159Z","time spent":"758.714477ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:02:09.311724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:08.544832Z","time spent":"766.805483ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T17:02:09.312081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.158293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-02T17:02:09.312121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.326104ms","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-04-02T17:02:09.312158Z","caller":"traceutil/trace.go:171","msg":"trace[1840374327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8317; }","duration":"752.272675ms","start":"2026-04-02T17:02:08.55987Z","end":"2026-04-02T17:02:09.312142Z","steps":["trace[1840374327] 'agreement among raft nodes before linearized reading' (duration: 752.022029ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:09.312194Z","caller":"traceutil/trace.go:171","msg":"trace[249786122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8317; }","duration":"676.362555ms","start":"2026-04-02T17:02:08.635782Z","end":"2026-04-02T17:02:09.312145Z","steps":["trace[249786122] 'agreement among raft nodes before linearized reading' (duration: 676.16736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:09.312206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:08.559854Z","time spent":"752.343498ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T17:02:09.312274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:08.635703Z","time spent":"676.56285ms","remote":"127.0.0.1:37898","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":"info","ts":"2026-04-02T17:02:09.314926Z","caller":"traceutil/trace.go:171","msg":"trace[2049226475] transaction","detail":"{read_only:false; response_revision:8318; number_of_response:1; }","duration":"446.052255ms","start":"2026-04-02T17:02:08.86885Z","end":"2026-04-02T17:02:09.314902Z","steps":["trace[2049226475] 'process raft request' (duration: 445.691355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:09.314955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.775032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T17:02:09.315025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:08.868835Z","time spent":"446.145297ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:02:09.315071Z","caller":"traceutil/trace.go:171","msg":"trace[467773147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8319; }","duration":"470.948066ms","start":"2026-04-02T17:02:08.844098Z","end":"2026-04-02T17:02:09.315046Z","steps":["trace[467773147] 'agreement among raft nodes before linearized reading' (duration: 470.785812ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:09.315088Z","caller":"traceutil/trace.go:171","msg":"trace[1243997555] transaction","detail":"{read_only:false; response_revision:8319; number_of_response:1; }","duration":"420.341969ms","start":"2026-04-02T17:02:08.894734Z","end":"2026-04-02T17:02:09.315076Z","steps":["trace[1243997555] 'process raft request' (duration: 420.061232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:09.315133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:08.844077Z","time spent":"471.045339ms","remote":"127.0.0.1:37590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T17:02:09.315124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.893611ms","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-04-02T17:02:09.315205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:08.894713Z","time spent":"420.415972ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:02:09.315205Z","caller":"traceutil/trace.go:171","msg":"trace[1723668766] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8319; }","duration":"212.014904ms","start":"2026-04-02T17:02:09.103171Z","end":"2026-04-02T17:02:09.315186Z","steps":["trace[1723668766] 'agreement among raft nodes before linearized reading' (duration: 211.761117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:09.315312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.497845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:02:09.315339Z","caller":"traceutil/trace.go:171","msg":"trace[1850913091] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:8319; }","duration":"393.565097ms","start":"2026-04-02T17:02:08.921765Z","end":"2026-04-02T17:02:09.31533Z","steps":["trace[1850913091] 'agreement among raft nodes before linearized reading' (duration: 393.523176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:09.315366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:08.921742Z","time spent":"393.614289ms","remote":"127.0.0.1:36938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-04-02T17:02:09.315369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.625393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T17:02:09.315416Z","caller":"traceutil/trace.go:171","msg":"trace[909137843] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:8319; }","duration":"374.713245ms","start":"2026-04-02T17:02:08.94069Z","end":"2026-04-02T17:02:09.315403Z","steps":["trace[909137843] 'agreement among raft nodes before linearized reading' (duration: 374.585071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:09.315455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:02:08.940676Z","time spent":"374.771626ms","remote":"127.0.0.1:56736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":36,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-04-02T17:02:20.657891Z","caller":"traceutil/trace.go:171","msg":"trace[1883815845] linearizableReadLoop","detail":"{readStateIndex:8676; appliedIndex:8675; }","duration":"156.967688ms","start":"2026-04-02T17:02:20.500891Z","end":"2026-04-02T17:02:20.657859Z","steps":["trace[1883815845] 'read index received' (duration: 79.320191ms)","trace[1883815845] 'applied index is now lower than readState.Index' (duration: 77.645847ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:02:20.658088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.141003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:02:20.658166Z","caller":"traceutil/trace.go:171","msg":"trace[665259483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8377; }","duration":"157.324128ms","start":"2026-04-02T17:02:20.500822Z","end":"2026-04-02T17:02:20.658146Z","steps":["trace[665259483] 'agreement among raft nodes before linearized reading' (duration: 157.133253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:21.729538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.419946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:02:21.729654Z","caller":"traceutil/trace.go:171","msg":"trace[883225522] transaction","detail":"{read_only:false; response_revision:8386; number_of_response:1; }","duration":"220.350526ms","start":"2026-04-02T17:02:21.509283Z","end":"2026-04-02T17:02:21.729633Z","steps":["trace[883225522] 'process raft request' (duration: 111.750015ms)","trace[883225522] 'compare' (duration: 108.20784ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:21.89911Z","caller":"traceutil/trace.go:171","msg":"trace[2027940943] linearizableReadLoop","detail":"{readStateIndex:8686; appliedIndex:8685; }","duration":"128.410608ms","start":"2026-04-02T17:02:21.770666Z","end":"2026-04-02T17:02:21.899076Z","steps":["trace[2027940943] 'read index received' (duration: 85.851185ms)","trace[2027940943] 'applied index is now lower than readState.Index' (duration: 42.558193ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:21.899194Z","caller":"traceutil/trace.go:171","msg":"trace[1565998707] transaction","detail":"{read_only:false; response_revision:8387; number_of_response:1; }","duration":"136.818962ms","start":"2026-04-02T17:02:21.762309Z","end":"2026-04-02T17:02:21.899128Z","steps":["trace[1565998707] 'process raft request' (duration: 94.30879ms)","trace[1565998707] 'compare' (duration: 42.215914ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:02:21.899371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.686555ms","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-02T17:02:21.899423Z","caller":"traceutil/trace.go:171","msg":"trace[66425530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8387; }","duration":"128.772678ms","start":"2026-04-02T17:02:21.770639Z","end":"2026-04-02T17:02:21.899412Z","steps":["trace[66425530] 'agreement among raft nodes before linearized reading' (duration: 128.600644ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:23.572152Z","caller":"traceutil/trace.go:171","msg":"trace[593702283] transaction","detail":"{read_only:false; response_revision:8391; number_of_response:1; }","duration":"191.479557ms","start":"2026-04-02T17:02:23.38064Z","end":"2026-04-02T17:02:23.572119Z","steps":["trace[593702283] 'process raft request' (duration: 191.276191ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:23.63538Z","caller":"traceutil/trace.go:171","msg":"trace[123905959] linearizableReadLoop","detail":"{readStateIndex:8693; appliedIndex:8689; }","duration":"211.51898ms","start":"2026-04-02T17:02:23.423842Z","end":"2026-04-02T17:02:23.635361Z","steps":["trace[123905959] 'read index received' (duration: 148.072311ms)","trace[123905959] 'applied index is now lower than readState.Index' (duration: 63.445509ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:23.635403Z","caller":"traceutil/trace.go:171","msg":"trace[206616432] transaction","detail":"{read_only:false; response_revision:8393; number_of_response:1; }","duration":"233.395483ms","start":"2026-04-02T17:02:23.401981Z","end":"2026-04-02T17:02:23.635377Z","steps":["trace[206616432] 'process raft request' (duration: 233.244479ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:23.635382Z","caller":"traceutil/trace.go:171","msg":"trace[2010521410] transaction","detail":"{read_only:false; response_revision:8392; number_of_response:1; }","duration":"233.689331ms","start":"2026-04-02T17:02:23.401646Z","end":"2026-04-02T17:02:23.635335Z","steps":["trace[2010521410] 'process raft request' (duration: 190.576453ms)","trace[2010521410] 'compare' (duration: 42.749618ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:02:23.635798Z","caller":"traceutil/trace.go:171","msg":"trace[1912933333] transaction","detail":"{read_only:false; response_revision:8394; number_of_response:1; }","duration":"233.095745ms","start":"2026-04-02T17:02:23.40257Z","end":"2026-04-02T17:02:23.635666Z","steps":["trace[1912933333] 'process raft request' (duration: 232.747376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:23.636058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.697669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-02T17:02:23.636067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.751957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T17:02:23.635891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.031475ms","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-02T17:02:23.636132Z","caller":"traceutil/trace.go:171","msg":"trace[329854778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8394; }","duration":"204.806252ms","start":"2026-04-02T17:02:23.431309Z","end":"2026-04-02T17:02:23.636115Z","steps":["trace[329854778] 'agreement among raft nodes before linearized reading' (duration: 204.604277ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:23.63616Z","caller":"traceutil/trace.go:171","msg":"trace[1819360586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8394; }","duration":"212.339412ms","start":"2026-04-02T17:02:23.423802Z","end":"2026-04-02T17:02:23.636141Z","steps":["trace[1819360586] 'agreement among raft nodes before linearized reading' (duration: 211.931621ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:02:23.636169Z","caller":"traceutil/trace.go:171","msg":"trace[204139211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8394; }","duration":"134.884701ms","start":"2026-04-02T17:02:23.501244Z","end":"2026-04-02T17:02:23.636129Z","steps":["trace[204139211] 'agreement among raft nodes before linearized reading' (duration: 134.660045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:02:23.636709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.003764ms","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-04-02T17:02:23.636821Z","caller":"traceutil/trace.go:171","msg":"trace[1050200388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8394; }","duration":"206.184018ms","start":"2026-04-02T17:02:23.430608Z","end":"2026-04-02T17:02:23.636792Z","steps":["trace[1050200388] 'agreement among raft nodes before linearized reading' (duration: 205.273664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:03:04.232336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.28039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T17:03:04.232474Z","caller":"traceutil/trace.go:171","msg":"trace[2136293694] linearizableReadLoop","detail":"{readStateIndex:9024; appliedIndex:9023; }","duration":"165.6994ms","start":"2026-04-02T17:03:04.066753Z","end":"2026-04-02T17:03:04.232452Z","steps":["trace[2136293694] 'read index received' (duration: 55.491µs)","trace[2136293694] 'applied index is now lower than readState.Index' (duration: 165.642249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:03:04.232635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.877846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:03:04.232681Z","caller":"traceutil/trace.go:171","msg":"trace[1881096514] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:8711; }","duration":"165.963048ms","start":"2026-04-02T17:03:04.066702Z","end":"2026-04-02T17:03:04.232665Z","steps":["trace[1881096514] 'agreement among raft nodes before linearized reading' (duration: 165.859245ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:03:04.232678Z","caller":"traceutil/trace.go:171","msg":"trace[541625253] transaction","detail":"{read_only:false; response_revision:8711; number_of_response:1; }","duration":"301.374423ms","start":"2026-04-02T17:03:03.931214Z","end":"2026-04-02T17:03:04.232588Z","steps":["trace[541625253] 'process raft request' (duration: 11.701322ms)","trace[541625253] 'compare' (duration: 289.165487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T17:03:04.232852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:03:03.931195Z","time spent":"301.564948ms","remote":"127.0.0.1:37898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T17:03:04.500277Z","caller":"traceutil/trace.go:171","msg":"trace[514828729] linearizableReadLoop","detail":"{readStateIndex:9025; appliedIndex:9024; }","duration":"238.249822ms","start":"2026-04-02T17:03:04.261999Z","end":"2026-04-02T17:03:04.500249Z","steps":["trace[514828729] 'read index received' (duration: 237.959054ms)","trace[514828729] 'applied index is now lower than readState.Index' (duration: 289.168µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:03:04.500363Z","caller":"traceutil/trace.go:171","msg":"trace[1382175971] transaction","detail":"{read_only:false; response_revision:8712; number_of_response:1; }","duration":"262.887338ms","start":"2026-04-02T17:03:04.23743Z","end":"2026-04-02T17:03:04.500318Z","steps":["trace[1382175971] 'process raft request' (duration: 262.644992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:03:04.500608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.589582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fhklx\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-02T17:03:04.500982Z","caller":"traceutil/trace.go:171","msg":"trace[1336310344] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fhklx; range_end:; response_count:1; response_revision:8712; }","duration":"238.956121ms","start":"2026-04-02T17:03:04.261936Z","end":"2026-04-02T17:03:04.500893Z","steps":["trace[1336310344] 'agreement among raft nodes before linearized reading' (duration: 238.461048ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:03:05.950882Z","caller":"traceutil/trace.go:171","msg":"trace[1422427905] linearizableReadLoop","detail":"{readStateIndex:9033; appliedIndex:9032; }","duration":"116.270885ms","start":"2026-04-02T17:03:05.834583Z","end":"2026-04-02T17:03:05.950854Z","steps":["trace[1422427905] 'read index received' (duration: 116.06213ms)","trace[1422427905] 'applied index is now lower than readState.Index' (duration: 207.525µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:03:05.950938Z","caller":"traceutil/trace.go:171","msg":"trace[983147242] transaction","detail":"{read_only:false; response_revision:8719; number_of_response:1; }","duration":"210.116774ms","start":"2026-04-02T17:03:05.74079Z","end":"2026-04-02T17:03:05.950907Z","steps":["trace[983147242] 'process raft request' (duration: 209.889528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:03:05.951135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.548913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-02T17:03:05.95118Z","caller":"traceutil/trace.go:171","msg":"trace[1636033227] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8719; }","duration":"116.665555ms","start":"2026-04-02T17:03:05.834501Z","end":"2026-04-02T17:03:05.951166Z","steps":["trace[1636033227] 'agreement among raft nodes before linearized reading' (duration: 116.484281ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:03:06.039255Z","caller":"traceutil/trace.go:171","msg":"trace[1303213448] transaction","detail":"{read_only:false; response_revision:8721; number_of_response:1; }","duration":"141.161018ms","start":"2026-04-02T17:03:05.898068Z","end":"2026-04-02T17:03:06.039229Z","steps":["trace[1303213448] 'process raft request' (duration: 141.080746ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:03:06.03951Z","caller":"traceutil/trace.go:171","msg":"trace[161026297] transaction","detail":"{read_only:false; response_revision:8722; number_of_response:1; }","duration":"138.020644ms","start":"2026-04-02T17:03:05.90145Z","end":"2026-04-02T17:03:06.039471Z","steps":["trace[161026297] 'process raft request' (duration: 137.739747ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:03:06.039293Z","caller":"traceutil/trace.go:171","msg":"trace[751515276] transaction","detail":"{read_only:false; response_revision:8720; number_of_response:1; }","duration":"154.467462ms","start":"2026-04-02T17:03:05.884774Z","end":"2026-04-02T17:03:06.039241Z","steps":["trace[751515276] 'process raft request' (duration: 154.189395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:03:06.039713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.22947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-02T17:03:06.039856Z","caller":"traceutil/trace.go:171","msg":"trace[1211259135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8722; }","duration":"126.426456ms","start":"2026-04-02T17:03:05.913406Z","end":"2026-04-02T17:03:06.039833Z","steps":["trace[1211259135] 'agreement among raft nodes before linearized reading' (duration: 126.083417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:03:06.03969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.734687ms","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-04-02T17:03:06.039993Z","caller":"traceutil/trace.go:171","msg":"trace[551240524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8722; }","duration":"111.073536ms","start":"2026-04-02T17:03:05.928893Z","end":"2026-04-02T17:03:06.039966Z","steps":["trace[551240524] 'agreement among raft nodes before linearized reading' (duration: 110.586823ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:03:20.497688Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d6.wal"} {"level":"info","ts":"2026-04-02T17:04:08.579552Z","caller":"traceutil/trace.go:171","msg":"trace[456296695] linearizableReadLoop","detail":"{readStateIndex:9715; appliedIndex:9713; }","duration":"248.692338ms","start":"2026-04-02T17:04:08.33084Z","end":"2026-04-02T17:04:08.579533Z","steps":["trace[456296695] 'read index received' (duration: 248.41369ms)","trace[456296695] 'applied index is now lower than readState.Index' (duration: 277.658µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:04:08.579614Z","caller":"traceutil/trace.go:171","msg":"trace[1635749976] transaction","detail":"{read_only:false; response_revision:9385; number_of_response:1; }","duration":"293.401097ms","start":"2026-04-02T17:04:08.286187Z","end":"2026-04-02T17:04:08.579589Z","steps":["trace[1635749976] 'process raft request' (duration: 293.274584ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:04:08.579635Z","caller":"traceutil/trace.go:171","msg":"trace[1280142012] transaction","detail":"{read_only:false; response_revision:9384; number_of_response:1; }","duration":"293.461499ms","start":"2026-04-02T17:04:08.286124Z","end":"2026-04-02T17:04:08.579586Z","steps":["trace[1280142012] 'process raft request' (duration: 293.016827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:04:08.579761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.179485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-02T17:04:08.579823Z","caller":"traceutil/trace.go:171","msg":"trace[805303130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9385; }","duration":"224.268098ms","start":"2026-04-02T17:04:08.35554Z","end":"2026-04-02T17:04:08.579808Z","steps":["trace[805303130] 'agreement among raft nodes before linearized reading' (duration: 224.125844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:04:08.579877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.024687ms","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-04-02T17:04:08.579932Z","caller":"traceutil/trace.go:171","msg":"trace[1123307805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9385; }","duration":"249.1367ms","start":"2026-04-02T17:04:08.330783Z","end":"2026-04-02T17:04:08.579919Z","steps":["trace[1123307805] 'agreement among raft nodes before linearized reading' (duration: 248.961105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:04:08.580005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.927557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T17:04:08.580049Z","caller":"traceutil/trace.go:171","msg":"trace[759604997] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9385; }","duration":"150.990788ms","start":"2026-04-02T17:04:08.429047Z","end":"2026-04-02T17:04:08.580038Z","steps":["trace[759604997] 'agreement among raft nodes before linearized reading' (duration: 150.883475ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:04:21.681907Z","caller":"traceutil/trace.go:171","msg":"trace[1331026730] linearizableReadLoop","detail":"{readStateIndex:9793; appliedIndex:9792; }","duration":"178.8858ms","start":"2026-04-02T17:04:21.502996Z","end":"2026-04-02T17:04:21.681882Z","steps":["trace[1331026730] 'read index received' (duration: 178.407157ms)","trace[1331026730] 'applied index is now lower than readState.Index' (duration: 477.593µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:04:21.682041Z","caller":"traceutil/trace.go:171","msg":"trace[1044317990] transaction","detail":"{read_only:false; response_revision:9460; number_of_response:1; }","duration":"186.196805ms","start":"2026-04-02T17:04:21.495779Z","end":"2026-04-02T17:04:21.681976Z","steps":["trace[1044317990] 'process raft request' (duration: 185.783664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:04:21.682131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.113397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:04:21.682304Z","caller":"traceutil/trace.go:171","msg":"trace[1603238864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9460; }","duration":"179.343793ms","start":"2026-04-02T17:04:21.502938Z","end":"2026-04-02T17:04:21.682282Z","steps":["trace[1603238864] 'agreement among raft nodes before linearized reading' (duration: 179.079346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:04:21.683193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.747367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:04:21.683327Z","caller":"traceutil/trace.go:171","msg":"trace[916220606] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:9460; }","duration":"170.773825ms","start":"2026-04-02T17:04:21.512519Z","end":"2026-04-02T17:04:21.683292Z","steps":["trace[916220606] 'agreement among raft nodes before linearized reading' (duration: 169.701476ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:04:48.797076Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"76a9cbc7029401f7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-02T17:04:48.802282Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-02T17:04:48.80239Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-02T17:07:02.439708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8153} {"level":"info","ts":"2026-04-02T17:07:02.506696Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8153,"took":"64.31306ms","hash":3355039296,"current-db-size-bytes":58748928,"current-db-size":"59 MB","current-db-size-in-use-bytes":28647424,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-02T17:07:02.506876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3355039296,"revision":8153,"compact-revision":6102} {"level":"info","ts":"2026-04-02T17:07:17.554733Z","caller":"traceutil/trace.go:171","msg":"trace[224184909] linearizableReadLoop","detail":"{readStateIndex:11135; appliedIndex:11134; }","duration":"115.886102ms","start":"2026-04-02T17:07:17.4388Z","end":"2026-04-02T17:07:17.554686Z","steps":["trace[224184909] 'read index received' (duration: 112.919063ms)","trace[224184909] 'applied index is now lower than readState.Index' (duration: 2.965369ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T17:07:17.554835Z","caller":"traceutil/trace.go:171","msg":"trace[895145365] transaction","detail":"{read_only:false; response_revision:10760; number_of_response:1; }","duration":"128.19427ms","start":"2026-04-02T17:07:17.426606Z","end":"2026-04-02T17:07:17.5548Z","steps":["trace[895145365] 'process raft request' (duration: 125.097057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T17:07:17.554955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.133368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T17:07:17.555077Z","caller":"traceutil/trace.go:171","msg":"trace[455070514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10760; }","duration":"116.311983ms","start":"2026-04-02T17:07:17.43874Z","end":"2026-04-02T17:07:17.555052Z","steps":["trace[455070514] 'agreement among raft nodes before linearized reading' (duration: 116.136149ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T17:12:02.44654Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10672} {"level":"info","ts":"2026-04-02T17:12:02.529139Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10672,"took":"80.768885ms","hash":2085559866,"current-db-size-bytes":58748928,"current-db-size":"59 MB","current-db-size-in-use-bytes":21356544,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-02T17:12:02.529198Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2085559866,"revision":10672,"compact-revision":8153}