{"level":"warn","ts":"2026-04-07T12:58:42.516612Z","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-07T12:58:42.516738Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.236: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.236:2380","--initial-cluster=instance=https://199.19.213.236:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.236:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.236: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-07T12:58:42.516828Z","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-07T12:58:42.516846Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.236:2380"]} {"level":"info","ts":"2026-04-07T12:58:42.516871Z","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-07T12:58:42.517762Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"]} {"level":"info","ts":"2026-04-07T12:58:42.517873Z","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.236:2380"],"listen-peer-urls":["https://199.19.213.236:2380"],"advertise-client-urls":["https://199.19.213.236:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.236: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-07T12:58:42.522691Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.532495ms"} {"level":"info","ts":"2026-04-07T12:58:42.529361Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f20dfa43a2e4607","cluster-id":"85a3671ea9bffea4"} {"level":"info","ts":"2026-04-07T12:58:42.529506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T12:58:42.529551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became follower at term 0"} {"level":"info","ts":"2026-04-07T12:58:42.52958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f20dfa43a2e4607 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T12:58:42.529593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became follower at term 1"} {"level":"info","ts":"2026-04-07T12:58:42.529645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=(6854724529301636615)"} {"level":"warn","ts":"2026-04-07T12:58:42.539828Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T12:58:42.543328Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T12:58:42.544643Z","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-07T12:58:42.547437Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f20dfa43a2e4607","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T12:58:42.547759Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f20dfa43a2e4607","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T12:58:42.547987Z","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-07T12:58:42.548116Z","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-07T12:58:42.548145Z","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-07T12:58:42.552792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=(6854724529301636615)"} {"level":"info","ts":"2026-04-07T12:58:42.553137Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"85a3671ea9bffea4","local-member-id":"5f20dfa43a2e4607","added-peer-id":"5f20dfa43a2e4607","added-peer-peer-urls":["https://199.19.213.236:2380"]} {"level":"info","ts":"2026-04-07T12:58:42.553219Z","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-07T12:58:42.553348Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.236:2380"} {"level":"info","ts":"2026-04-07T12:58:42.553388Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.236:2380"} {"level":"info","ts":"2026-04-07T12:58:42.5535Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f20dfa43a2e4607","initial-advertise-peer-urls":["https://199.19.213.236:2380"],"listen-peer-urls":["https://199.19.213.236:2380"],"advertise-client-urls":["https://199.19.213.236:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T12:58:42.553565Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T12:58:43.13084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T12:58:43.13092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T12:58:43.130971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 received MsgPreVoteResp from 5f20dfa43a2e4607 at term 1"} {"level":"info","ts":"2026-04-07T12:58:43.130991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became candidate at term 2"} {"level":"info","ts":"2026-04-07T12:58:43.131001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 received MsgVoteResp from 5f20dfa43a2e4607 at term 2"} {"level":"info","ts":"2026-04-07T12:58:43.131029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became leader at term 2"} {"level":"info","ts":"2026-04-07T12:58:43.131042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f20dfa43a2e4607 elected leader 5f20dfa43a2e4607 at term 2"} {"level":"info","ts":"2026-04-07T12:58:43.131863Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:58:43.13275Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:58:43.132739Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f20dfa43a2e4607","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.236:2379]}","request-path":"/0/members/5f20dfa43a2e4607/attributes","cluster-id":"85a3671ea9bffea4","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T12:58:43.132873Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:58:43.133166Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T12:58:43.133227Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T12:58:43.134307Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"85a3671ea9bffea4","local-member-id":"5f20dfa43a2e4607","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:58:43.134453Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:58:43.134503Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:58:43.136678Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T12:58:43.139323Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.236:2379"} {"level":"warn","ts":"2026-04-07T12:59:01.793277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.905896ms","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-07T12:59:01.793411Z","caller":"traceutil/trace.go:171","msg":"trace[949168013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:451; }","duration":"354.117081ms","start":"2026-04-07T12:59:01.439268Z","end":"2026-04-07T12:59:01.793385Z","steps":["trace[949168013] 'range keys from in-memory index tree' (duration: 353.783474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:01.793483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:01.439249Z","time spent":"354.217463ms","remote":"127.0.0.1:60980","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-07T12:59:07.299081Z","caller":"traceutil/trace.go:171","msg":"trace[945740711] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"359.917551ms","start":"2026-04-07T12:59:06.939126Z","end":"2026-04-07T12:59:07.299044Z","steps":["trace[945740711] 'read index received' (duration: 359.653915ms)","trace[945740711] 'applied index is now lower than readState.Index' (duration: 262.056µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:59:07.299205Z","caller":"traceutil/trace.go:171","msg":"trace[27409441] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"367.552664ms","start":"2026-04-07T12:59:06.931595Z","end":"2026-04-07T12:59:07.299148Z","steps":["trace[27409441] 'process raft request' (duration: 367.141815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:07.29933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.143326ms","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-07T12:59:07.299379Z","caller":"traceutil/trace.go:171","msg":"trace[79997178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:464; }","duration":"360.266088ms","start":"2026-04-07T12:59:06.939097Z","end":"2026-04-07T12:59:07.299363Z","steps":["trace[79997178] 'agreement among raft nodes before linearized reading' (duration: 360.119735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:07.299425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:06.939086Z","time spent":"360.32972ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T12:59:07.300136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:06.931573Z","time spent":"367.752718ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:59:11.645612Z","caller":"traceutil/trace.go:171","msg":"trace[1612765142] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"322.424825ms","start":"2026-04-07T12:59:11.323167Z","end":"2026-04-07T12:59:11.645592Z","steps":["trace[1612765142] 'process raft request' (duration: 322.302791ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:11.645633Z","caller":"traceutil/trace.go:171","msg":"trace[1164623804] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:487; }","duration":"318.743771ms","start":"2026-04-07T12:59:11.326856Z","end":"2026-04-07T12:59:11.6456Z","steps":["trace[1164623804] 'read index received' (duration: 318.728361ms)","trace[1164623804] 'applied index is now lower than readState.Index' (duration: 12.84µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:59:11.645742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:11.323151Z","time spent":"322.526178ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:59:11.645951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.075169ms","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-07T12:59:11.645994Z","caller":"traceutil/trace.go:171","msg":"trace[852540365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:473; }","duration":"319.15249ms","start":"2026-04-07T12:59:11.326831Z","end":"2026-04-07T12:59:11.645984Z","steps":["trace[852540365] 'agreement among raft nodes before linearized reading' (duration: 318.872374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:11.646086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:11.326821Z","time spent":"319.192841ms","remote":"127.0.0.1:60980","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-07T12:59:14.196871Z","caller":"traceutil/trace.go:171","msg":"trace[980720503] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"505.732545ms","start":"2026-04-07T12:59:13.691101Z","end":"2026-04-07T12:59:14.196834Z","steps":["trace[980720503] 'process raft request' (duration: 505.376127ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:14.196902Z","caller":"traceutil/trace.go:171","msg":"trace[163151755] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"502.749587ms","start":"2026-04-07T12:59:13.694125Z","end":"2026-04-07T12:59:14.196875Z","steps":["trace[163151755] 'process raft request' (duration: 502.699446ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:14.196968Z","caller":"traceutil/trace.go:171","msg":"trace[1607432228] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"504.554809ms","start":"2026-04-07T12:59:13.692395Z","end":"2026-04-07T12:59:14.19695Z","steps":["trace[1607432228] 'process raft request' (duration: 504.344554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:14.197021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.691091Z","time spent":"505.853318ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:59:14.197036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.692388Z","time spent":"504.62025ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:59:14.197079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.694117Z","time spent":"502.87196ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:59:14.204988Z","caller":"traceutil/trace.go:171","msg":"trace[1417167881] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"502.962302ms","start":"2026-04-07T12:59:13.702002Z","end":"2026-04-07T12:59:14.204964Z","steps":["trace[1417167881] 'process raft request' (duration: 502.658355ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:14.205032Z","caller":"traceutil/trace.go:171","msg":"trace[1144802374] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"502.819479ms","start":"2026-04-07T12:59:13.702198Z","end":"2026-04-07T12:59:14.205018Z","steps":["trace[1144802374] 'process raft request' (duration: 502.691926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:14.205072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.701972Z","time spent":"503.061745ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:59:14.205093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.70219Z","time spent":"502.87557ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:59:14.205184Z","caller":"traceutil/trace.go:171","msg":"trace[379844111] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"501.989811ms","start":"2026-04-07T12:59:13.703136Z","end":"2026-04-07T12:59:14.205126Z","steps":["trace[379844111] 'process raft request' (duration: 501.835157ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:14.205226Z","caller":"traceutil/trace.go:171","msg":"trace[901238589] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"501.404537ms","start":"2026-04-07T12:59:13.70375Z","end":"2026-04-07T12:59:14.205155Z","steps":["trace[901238589] 'process raft request' (duration: 501.272554ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:14.205279Z","caller":"traceutil/trace.go:171","msg":"trace[1170101612] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"499.589735ms","start":"2026-04-07T12:59:13.705642Z","end":"2026-04-07T12:59:14.205232Z","steps":["trace[1170101612] 'process raft request' (duration: 499.420101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:14.205335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.703117Z","time spent":"502.146774ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:59:14.205472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.703728Z","time spent":"501.628922ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:59:14.2055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.705632Z","time spent":"499.75717ms","remote":"127.0.0.1:60980","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-07T12:59:14.205627Z","caller":"traceutil/trace.go:171","msg":"trace[1213312096] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"493.901715ms","start":"2026-04-07T12:59:13.711693Z","end":"2026-04-07T12:59:14.205595Z","steps":["trace[1213312096] 'process raft request' (duration: 493.455675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:14.205771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.711685Z","time spent":"494.030549ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:59:14.205983Z","caller":"traceutil/trace.go:171","msg":"trace[530948988] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"492.201997ms","start":"2026-04-07T12:59:13.713767Z","end":"2026-04-07T12:59:14.205969Z","steps":["trace[530948988] 'process raft request' (duration: 491.724626ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:14.206023Z","caller":"traceutil/trace.go:171","msg":"trace[1144992247] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:526; }","duration":"347.827625ms","start":"2026-04-07T12:59:13.858183Z","end":"2026-04-07T12:59:14.20601Z","steps":["trace[1144992247] 'read index received' (duration: 338.368538ms)","trace[1144992247] 'applied index is now lower than readState.Index' (duration: 9.458347ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:59:14.206055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.713758Z","time spent":"492.268629ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:59:14.206084Z","caller":"traceutil/trace.go:171","msg":"trace[620228291] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"324.488552ms","start":"2026-04-07T12:59:13.881586Z","end":"2026-04-07T12:59:14.206075Z","steps":["trace[620228291] 'process raft request' (duration: 324.312817ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:14.206079Z","caller":"traceutil/trace.go:171","msg":"trace[1785989578] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"451.249574ms","start":"2026-04-07T12:59:13.754813Z","end":"2026-04-07T12:59:14.206062Z","steps":["trace[1785989578] 'process raft request' (duration: 450.987697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:14.206127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.881544Z","time spent":"324.560644ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:59:14.206171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.754795Z","time spent":"451.333556ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:59:14.206247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.067551ms","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-07T12:59:14.206281Z","caller":"traceutil/trace.go:171","msg":"trace[143858518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"348.148012ms","start":"2026-04-07T12:59:13.858124Z","end":"2026-04-07T12:59:14.206272Z","steps":["trace[143858518] 'agreement among raft nodes before linearized reading' (duration: 347.992269ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:14.206092Z","caller":"traceutil/trace.go:171","msg":"trace[754778615] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"467.226538ms","start":"2026-04-07T12:59:13.738855Z","end":"2026-04-07T12:59:14.206081Z","steps":["trace[754778615] 'process raft request' (duration: 466.865489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:14.206382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.738812Z","time spent":"467.541355ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:59:14.206321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:13.858105Z","time spent":"348.210843ms","remote":"127.0.0.1:60980","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-07T12:59:20.882026Z","caller":"traceutil/trace.go:171","msg":"trace[1925034921] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"201.059117ms","start":"2026-04-07T12:59:20.680923Z","end":"2026-04-07T12:59:20.881982Z","steps":["trace[1925034921] 'process raft request' (duration: 200.306249ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:21.457491Z","caller":"traceutil/trace.go:171","msg":"trace[598820096] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"514.442225ms","start":"2026-04-07T12:59:20.943022Z","end":"2026-04-07T12:59:21.457464Z","steps":["trace[598820096] 'process raft request' (duration: 514.22727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:21.457715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:20.942968Z","time spent":"514.68522ms","remote":"127.0.0.1:60944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T12:59:22.963824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.218109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:59:22.964182Z","caller":"traceutil/trace.go:171","msg":"trace[1067823588] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.490305865s","start":"2026-04-07T12:59:21.473848Z","end":"2026-04-07T12:59:22.964154Z","steps":["trace[1067823588] 'process raft request' (duration: 559.547044ms)","trace[1067823588] 'compare' (duration: 930.047875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:59:22.964364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:21.473816Z","time spent":"1.490432097s","remote":"127.0.0.1:60874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:59:22.982717Z","caller":"traceutil/trace.go:171","msg":"trace[144735391] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"1.222917236s","start":"2026-04-07T12:59:21.75978Z","end":"2026-04-07T12:59:22.982697Z","steps":["trace[144735391] 'read index received' (duration: 273.638012ms)","trace[144735391] 'applied index is now lower than readState.Index' (duration: 949.277943ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:59:22.98299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223191052s","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-07T12:59:22.983091Z","caller":"traceutil/trace.go:171","msg":"trace[850641705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"1.223349376s","start":"2026-04-07T12:59:21.75972Z","end":"2026-04-07T12:59:22.983069Z","steps":["trace[850641705] 'agreement among raft nodes before linearized reading' (duration: 1.223039729s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:22.983146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:21.759692Z","time spent":"1.223444597s","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T12:59:22.989573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.080955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T12:59:22.989666Z","caller":"traceutil/trace.go:171","msg":"trace[778105743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"745.230358ms","start":"2026-04-07T12:59:22.244417Z","end":"2026-04-07T12:59:22.989647Z","steps":["trace[778105743] 'agreement among raft nodes before linearized reading' (duration: 745.014993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:22.989712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:22.244397Z","time spent":"745.30203ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T12:59:22.989821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.034352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:59:22.989903Z","caller":"traceutil/trace.go:171","msg":"trace[2071932527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"573.156724ms","start":"2026-04-07T12:59:22.416729Z","end":"2026-04-07T12:59:22.989886Z","steps":["trace[2071932527] 'agreement among raft nodes before linearized reading' (duration: 573.018791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:22.989934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:22.416703Z","time spent":"573.225646ms","remote":"127.0.0.1:60688","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-07T12:59:22.990127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.967244ms","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-07T12:59:22.99019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.55589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T12:59:22.990253Z","caller":"traceutil/trace.go:171","msg":"trace[1540384985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"290.127907ms","start":"2026-04-07T12:59:22.700089Z","end":"2026-04-07T12:59:22.990217Z","steps":["trace[1540384985] 'agreement among raft nodes before linearized reading' (duration: 289.847761ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:22.990265Z","caller":"traceutil/trace.go:171","msg":"trace[1520232682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"700.687702ms","start":"2026-04-07T12:59:22.289548Z","end":"2026-04-07T12:59:22.990236Z","steps":["trace[1520232682] 'agreement among raft nodes before linearized reading' (duration: 700.475838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:22.990316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:22.289516Z","time spent":"700.791815ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T12:59:37.594474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.007052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:59:37.595099Z","caller":"traceutil/trace.go:171","msg":"trace[1865065023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"179.709971ms","start":"2026-04-07T12:59:37.415358Z","end":"2026-04-07T12:59:37.595068Z","steps":["trace[1865065023] 'range keys from in-memory index tree' (duration: 178.876629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:37.594931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.71659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vcjlt\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-07T12:59:37.595217Z","caller":"traceutil/trace.go:171","msg":"trace[1313897424] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vcjlt; range_end:; response_count:1; response_revision:787; }","duration":"180.015607ms","start":"2026-04-07T12:59:37.415156Z","end":"2026-04-07T12:59:37.595171Z","steps":["trace[1313897424] 'range keys from in-memory index tree' (duration: 179.400043ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:37.910989Z","caller":"traceutil/trace.go:171","msg":"trace[734250118] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"306.694576ms","start":"2026-04-07T12:59:37.604269Z","end":"2026-04-07T12:59:37.910964Z","steps":["trace[734250118] 'process raft request' (duration: 306.43276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:37.911188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:37.604244Z","time spent":"306.833759ms","remote":"127.0.0.1:60874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:00:06.455596Z","caller":"traceutil/trace.go:171","msg":"trace[575455752] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1210; }","duration":"238.531471ms","start":"2026-04-07T13:00:06.217025Z","end":"2026-04-07T13:00:06.455557Z","steps":["trace[575455752] 'read index received' (duration: 238.247225ms)","trace[575455752] 'applied index is now lower than readState.Index' (duration: 282.806µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:00:06.455679Z","caller":"traceutil/trace.go:171","msg":"trace[352919482] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"460.590386ms","start":"2026-04-07T13:00:05.995052Z","end":"2026-04-07T13:00:06.455642Z","steps":["trace[352919482] 'process raft request' (duration: 460.234348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:06.455853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:05.995012Z","time spent":"460.75186ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:00:06.455895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.855198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.236\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T13:00:06.45611Z","caller":"traceutil/trace.go:171","msg":"trace[1072141595] range","detail":"{range_begin:/registry/masterleases/199.19.213.236; range_end:; response_count:1; response_revision:1181; }","duration":"239.093094ms","start":"2026-04-07T13:00:06.216975Z","end":"2026-04-07T13:00:06.456068Z","steps":["trace[1072141595] 'agreement among raft nodes before linearized reading' (duration: 238.759766ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:21.795983Z","caller":"traceutil/trace.go:171","msg":"trace[1313365797] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"148.935959ms","start":"2026-04-07T13:00:21.647004Z","end":"2026-04-07T13:00:21.79594Z","steps":["trace[1313365797] 'process raft request' (duration: 148.672853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:28.948123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.6199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:28.948693Z","caller":"traceutil/trace.go:171","msg":"trace[2029154248] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1452; }","duration":"123.253204ms","start":"2026-04-07T13:00:28.825407Z","end":"2026-04-07T13:00:28.94866Z","steps":["trace[2029154248] 'count revisions from in-memory index tree' (duration: 122.497126ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:47.601684Z","caller":"traceutil/trace.go:171","msg":"trace[1799328098] linearizableReadLoop","detail":"{readStateIndex:1809; appliedIndex:1808; }","duration":"138.447791ms","start":"2026-04-07T13:00:47.463206Z","end":"2026-04-07T13:00:47.601654Z","steps":["trace[1799328098] 'read index received' (duration: 138.290528ms)","trace[1799328098] 'applied index is now lower than readState.Index' (duration: 156.383µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:00:47.601726Z","caller":"traceutil/trace.go:171","msg":"trace[294711435] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"138.8902ms","start":"2026-04-07T13:00:47.462811Z","end":"2026-04-07T13:00:47.601701Z","steps":["trace[294711435] 'process raft request' (duration: 138.634024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:47.602241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.007714ms","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-07T13:00:47.602324Z","caller":"traceutil/trace.go:171","msg":"trace[1749962261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1743; }","duration":"139.127636ms","start":"2026-04-07T13:00:47.463178Z","end":"2026-04-07T13:00:47.602305Z","steps":["trace[1749962261] 'agreement among raft nodes before linearized reading' (duration: 138.828219ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:47.607207Z","caller":"traceutil/trace.go:171","msg":"trace[2038292282] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"140.890257ms","start":"2026-04-07T13:00:47.466271Z","end":"2026-04-07T13:00:47.607161Z","steps":["trace[2038292282] 'process raft request' (duration: 140.709683ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:47.607207Z","caller":"traceutil/trace.go:171","msg":"trace[777324229] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"122.67326ms","start":"2026-04-07T13:00:47.484507Z","end":"2026-04-07T13:00:47.60718Z","steps":["trace[777324229] 'process raft request' (duration: 122.583428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:47.607283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.206935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T13:00:47.607237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.639699ms","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-07T13:00:47.607329Z","caller":"traceutil/trace.go:171","msg":"trace[2123565452] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1745; }","duration":"138.293857ms","start":"2026-04-07T13:00:47.469024Z","end":"2026-04-07T13:00:47.607318Z","steps":["trace[2123565452] 'agreement among raft nodes before linearized reading' (duration: 138.175245ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:47.607373Z","caller":"traceutil/trace.go:171","msg":"trace[443092824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1745; }","duration":"143.803273ms","start":"2026-04-07T13:00:47.463553Z","end":"2026-04-07T13:00:47.607356Z","steps":["trace[443092824] 'agreement among raft nodes before linearized reading' (duration: 143.581678ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:57.132346Z","caller":"traceutil/trace.go:171","msg":"trace[1071387730] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"194.777297ms","start":"2026-04-07T13:00:56.93754Z","end":"2026-04-07T13:00:57.132318Z","steps":["trace[1071387730] 'read index received' (duration: 194.593512ms)","trace[1071387730] 'applied index is now lower than readState.Index' (duration: 182.425µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:00:57.132492Z","caller":"traceutil/trace.go:171","msg":"trace[290952484] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"306.291412ms","start":"2026-04-07T13:00:56.826155Z","end":"2026-04-07T13:00:57.132446Z","steps":["trace[290952484] 'process raft request' (duration: 305.991395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:57.13261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.061513ms","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-07T13:00:57.132659Z","caller":"traceutil/trace.go:171","msg":"trace[1190794995] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1864; }","duration":"195.162635ms","start":"2026-04-07T13:00:56.937484Z","end":"2026-04-07T13:00:57.132646Z","steps":["trace[1190794995] 'agreement among raft nodes before linearized reading' (duration: 194.998931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:57.132611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.792282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:00:57.132722Z","caller":"traceutil/trace.go:171","msg":"trace[1651501259] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1864; }","duration":"150.908334ms","start":"2026-04-07T13:00:56.981802Z","end":"2026-04-07T13:00:57.13271Z","steps":["trace[1651501259] 'agreement among raft nodes before linearized reading' (duration: 150.72959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:57.132784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:56.826115Z","time spent":"306.530267ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:00:57.552982Z","caller":"traceutil/trace.go:171","msg":"trace[1163758663] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"378.95942ms","start":"2026-04-07T13:00:57.173972Z","end":"2026-04-07T13:00:57.552931Z","steps":["trace[1163758663] 'process raft request' (duration: 344.041604ms)","trace[1163758663] 'compare' (duration: 33.795761ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:00:57.55298Z","caller":"traceutil/trace.go:171","msg":"trace[1518859993] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"303.479827ms","start":"2026-04-07T13:00:57.249267Z","end":"2026-04-07T13:00:57.552747Z","steps":["trace[1518859993] 'read index received' (duration: 268.679123ms)","trace[1518859993] 'applied index is now lower than readState.Index' (duration: 34.799564ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:00:57.553218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:57.173944Z","time spent":"379.153454ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:00:57.553254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.952467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-028c7c70-73f2-48f5-b2be-d78d6f9b566e\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-07T13:00:57.553383Z","caller":"traceutil/trace.go:171","msg":"trace[1731764372] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-028c7c70-73f2-48f5-b2be-d78d6f9b566e; range_end:; response_count:1; response_revision:1865; }","duration":"304.160402ms","start":"2026-04-07T13:00:57.249194Z","end":"2026-04-07T13:00:57.553355Z","steps":["trace[1731764372] 'agreement among raft nodes before linearized reading' (duration: 303.805164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:57.553306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.210572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:00:57.553444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:57.249164Z","time spent":"304.267935ms","remote":"127.0.0.1:60874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-028c7c70-73f2-48f5-b2be-d78d6f9b566e\" "} {"level":"info","ts":"2026-04-07T13:00:57.553492Z","caller":"traceutil/trace.go:171","msg":"trace[695060817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1865; }","duration":"137.453747ms","start":"2026-04-07T13:00:57.415998Z","end":"2026-04-07T13:00:57.553452Z","steps":["trace[695060817] 'agreement among raft nodes before linearized reading' (duration: 137.240552ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:58.155054Z","caller":"traceutil/trace.go:171","msg":"trace[1488113008] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1933; }","duration":"497.908056ms","start":"2026-04-07T13:00:57.657116Z","end":"2026-04-07T13:00:58.155024Z","steps":["trace[1488113008] 'read index received' (duration: 423.17494ms)","trace[1488113008] 'applied index is now lower than readState.Index' (duration: 74.731726ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:00:58.155112Z","caller":"traceutil/trace.go:171","msg":"trace[1891080590] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"503.627126ms","start":"2026-04-07T13:00:57.651426Z","end":"2026-04-07T13:00:58.155053Z","steps":["trace[1891080590] 'process raft request' (duration: 428.768367ms)","trace[1891080590] 'compare' (duration: 74.692045ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:00:58.155387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:57.651396Z","time spent":"503.831331ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:00:58.155402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.248413ms","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-07T13:00:58.155573Z","caller":"traceutil/trace.go:171","msg":"trace[630696331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1866; }","duration":"498.493949ms","start":"2026-04-07T13:00:57.657043Z","end":"2026-04-07T13:00:58.155537Z","steps":["trace[630696331] 'agreement among raft nodes before linearized reading' (duration: 498.083719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:58.155641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:57.657009Z","time spent":"498.615352ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T13:00:58.156053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.773224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:00:58.156086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.947829ms","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-07T13:00:58.156111Z","caller":"traceutil/trace.go:171","msg":"trace[1536300543] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1867; }","duration":"105.841975ms","start":"2026-04-07T13:00:58.050252Z","end":"2026-04-07T13:00:58.156094Z","steps":["trace[1536300543] 'agreement among raft nodes before linearized reading' (duration: 105.691582ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:58.156135Z","caller":"traceutil/trace.go:171","msg":"trace[882518223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1867; }","duration":"499.054172ms","start":"2026-04-07T13:00:57.657065Z","end":"2026-04-07T13:00:58.15612Z","steps":["trace[882518223] 'agreement among raft nodes before linearized reading' (duration: 498.855737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:58.156195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:57.657025Z","time spent":"499.146774ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T13:00:58.156254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.028311ms","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-07T13:00:58.156287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.949799ms","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-07T13:00:58.156339Z","caller":"traceutil/trace.go:171","msg":"trace[1086450904] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"283.781386ms","start":"2026-04-07T13:00:57.872505Z","end":"2026-04-07T13:00:58.156286Z","steps":["trace[1086450904] 'process raft request' (duration: 283.254065ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:58.156415Z","caller":"traceutil/trace.go:171","msg":"trace[66379934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1867; }","duration":"499.148934ms","start":"2026-04-07T13:00:57.657237Z","end":"2026-04-07T13:00:58.156386Z","steps":["trace[66379934] 'agreement among raft nodes before linearized reading' (duration: 498.740294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:58.15648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:57.657204Z","time spent":"499.265077ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T13:00:58.156607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.339734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"info","ts":"2026-04-07T13:00:58.156732Z","caller":"traceutil/trace.go:171","msg":"trace[233538147] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1867; }","duration":"204.504168ms","start":"2026-04-07T13:00:57.952202Z","end":"2026-04-07T13:00:58.156707Z","steps":["trace[233538147] 'agreement among raft nodes before linearized reading' (duration: 203.993395ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:58.156317Z","caller":"traceutil/trace.go:171","msg":"trace[748661074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1867; }","duration":"499.155584ms","start":"2026-04-07T13:00:57.657145Z","end":"2026-04-07T13:00:58.156301Z","steps":["trace[748661074] 'agreement among raft nodes before linearized reading' (duration: 498.863847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:58.157892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:57.657106Z","time spent":"500.767851ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T13:01:29.409983Z","caller":"traceutil/trace.go:171","msg":"trace[141222124] linearizableReadLoop","detail":"{readStateIndex:2140; appliedIndex:2139; }","duration":"103.142828ms","start":"2026-04-07T13:01:29.306816Z","end":"2026-04-07T13:01:29.409958Z","steps":["trace[141222124] 'read index received' (duration: 102.949674ms)","trace[141222124] 'applied index is now lower than readState.Index' (duration: 191.744µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:01:29.410054Z","caller":"traceutil/trace.go:171","msg":"trace[840356637] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"105.227986ms","start":"2026-04-07T13:01:29.304786Z","end":"2026-04-07T13:01:29.410014Z","steps":["trace[840356637] 'process raft request' (duration: 104.96781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:29.410369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.528807ms","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-07T13:01:29.410485Z","caller":"traceutil/trace.go:171","msg":"trace[661204379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2065; }","duration":"103.740082ms","start":"2026-04-07T13:01:29.306721Z","end":"2026-04-07T13:01:29.410461Z","steps":["trace[661204379] 'agreement among raft nodes before linearized reading' (duration: 103.380134ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:12.710847Z","caller":"traceutil/trace.go:171","msg":"trace[1396930142] linearizableReadLoop","detail":"{readStateIndex:2399; appliedIndex:2398; }","duration":"153.490091ms","start":"2026-04-07T13:02:12.557332Z","end":"2026-04-07T13:02:12.710822Z","steps":["trace[1396930142] 'read index received' (duration: 153.163633ms)","trace[1396930142] 'applied index is now lower than readState.Index' (duration: 324.838µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:02:12.710887Z","caller":"traceutil/trace.go:171","msg":"trace[862928131] transaction","detail":"{read_only:false; response_revision:2314; number_of_response:1; }","duration":"183.600273ms","start":"2026-04-07T13:02:12.527236Z","end":"2026-04-07T13:02:12.710836Z","steps":["trace[862928131] 'process raft request' (duration: 183.370428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:12.711151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.951965ms","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-07T13:02:12.71119Z","caller":"traceutil/trace.go:171","msg":"trace[2004006672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2314; }","duration":"137.016357ms","start":"2026-04-07T13:02:12.574164Z","end":"2026-04-07T13:02:12.711181Z","steps":["trace[2004006672] 'agreement among raft nodes before linearized reading' (duration: 136.834123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:12.711184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.546743ms","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-07T13:02:12.711305Z","caller":"traceutil/trace.go:171","msg":"trace[1351389765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2314; }","duration":"135.730537ms","start":"2026-04-07T13:02:12.575548Z","end":"2026-04-07T13:02:12.711279Z","steps":["trace[1351389765] 'agreement among raft nodes before linearized reading' (duration: 135.484311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:12.711183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.824428ms","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-07T13:02:12.711337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.098825ms","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-07T13:02:12.711429Z","caller":"traceutil/trace.go:171","msg":"trace[772037748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2314; }","duration":"154.088494ms","start":"2026-04-07T13:02:12.557312Z","end":"2026-04-07T13:02:12.7114Z","steps":["trace[772037748] 'agreement among raft nodes before linearized reading' (duration: 153.614253ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:12.711459Z","caller":"traceutil/trace.go:171","msg":"trace[932624059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2314; }","duration":"136.260579ms","start":"2026-04-07T13:02:12.575173Z","end":"2026-04-07T13:02:12.711434Z","steps":["trace[932624059] 'agreement among raft nodes before linearized reading' (duration: 135.917932ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:12.872974Z","caller":"traceutil/trace.go:171","msg":"trace[2035459782] transaction","detail":"{read_only:false; response_revision:2317; number_of_response:1; }","duration":"156.501358ms","start":"2026-04-07T13:02:12.716438Z","end":"2026-04-07T13:02:12.87294Z","steps":["trace[2035459782] 'process raft request' (duration: 156.412106ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:12.872972Z","caller":"traceutil/trace.go:171","msg":"trace[963815296] transaction","detail":"{read_only:false; response_revision:2316; number_of_response:1; }","duration":"156.731243ms","start":"2026-04-07T13:02:12.716196Z","end":"2026-04-07T13:02:12.872927Z","steps":["trace[963815296] 'process raft request' (duration: 156.507568ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:12.873507Z","caller":"traceutil/trace.go:171","msg":"trace[933823630] transaction","detail":"{read_only:false; response_revision:2319; number_of_response:1; }","duration":"107.146648ms","start":"2026-04-07T13:02:12.766335Z","end":"2026-04-07T13:02:12.873482Z","steps":["trace[933823630] 'process raft request' (duration: 106.822321ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:12.873269Z","caller":"traceutil/trace.go:171","msg":"trace[2070616438] transaction","detail":"{read_only:false; response_revision:2318; number_of_response:1; }","duration":"156.418096ms","start":"2026-04-07T13:02:12.716836Z","end":"2026-04-07T13:02:12.873254Z","steps":["trace[2070616438] 'process raft request' (duration: 156.058618ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:13.150234Z","caller":"traceutil/trace.go:171","msg":"trace[1050135668] linearizableReadLoop","detail":"{readStateIndex:2405; appliedIndex:2404; }","duration":"267.847362ms","start":"2026-04-07T13:02:12.882347Z","end":"2026-04-07T13:02:13.150194Z","steps":["trace[1050135668] 'read index received' (duration: 238.881445ms)","trace[1050135668] 'applied index is now lower than readState.Index' (duration: 28.964587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:02:13.150462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.060078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:02:13.150499Z","caller":"traceutil/trace.go:171","msg":"trace[718124695] transaction","detail":"{read_only:false; response_revision:2320; number_of_response:1; }","duration":"270.690478ms","start":"2026-04-07T13:02:12.879604Z","end":"2026-04-07T13:02:13.150295Z","steps":["trace[718124695] 'process raft request' (duration: 241.146017ms)","trace[718124695] 'compare' (duration: 29.260824ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:02:13.150541Z","caller":"traceutil/trace.go:171","msg":"trace[674317986] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2320; }","duration":"268.231912ms","start":"2026-04-07T13:02:12.882287Z","end":"2026-04-07T13:02:13.150519Z","steps":["trace[674317986] 'agreement among raft nodes before linearized reading' (duration: 268.061918ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:19.088219Z","caller":"traceutil/trace.go:171","msg":"trace[352216668] linearizableReadLoop","detail":"{readStateIndex:2434; appliedIndex:2433; }","duration":"190.420236ms","start":"2026-04-07T13:02:18.897772Z","end":"2026-04-07T13:02:19.088192Z","steps":["trace[352216668] 'read index received' (duration: 190.14335ms)","trace[352216668] 'applied index is now lower than readState.Index' (duration: 275.886µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:02:19.088652Z","caller":"traceutil/trace.go:171","msg":"trace[165969820] transaction","detail":"{read_only:false; response_revision:2348; number_of_response:1; }","duration":"191.685577ms","start":"2026-04-07T13:02:18.896947Z","end":"2026-04-07T13:02:19.088633Z","steps":["trace[165969820] 'process raft request' (duration: 191.087752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:19.088916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.898028ms","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-07T13:02:19.08901Z","caller":"traceutil/trace.go:171","msg":"trace[1893780385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2348; }","duration":"191.032491ms","start":"2026-04-07T13:02:18.897952Z","end":"2026-04-07T13:02:19.088985Z","steps":["trace[1893780385] 'agreement among raft nodes before linearized reading' (duration: 190.743774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:19.08902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.241886ms","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-07T13:02:19.089061Z","caller":"traceutil/trace.go:171","msg":"trace[1261725458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2348; }","duration":"191.356129ms","start":"2026-04-07T13:02:18.89769Z","end":"2026-04-07T13:02:19.089047Z","steps":["trace[1261725458] 'agreement among raft nodes before linearized reading' (duration: 191.200555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:19.089064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.374147ms","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-07T13:02:19.08925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.045676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T13:02:19.089354Z","caller":"traceutil/trace.go:171","msg":"trace[96432486] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2348; }","duration":"168.734765ms","start":"2026-04-07T13:02:18.920572Z","end":"2026-04-07T13:02:19.089307Z","steps":["trace[96432486] 'agreement among raft nodes before linearized reading' (duration: 168.155522ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:19.089435Z","caller":"traceutil/trace.go:171","msg":"trace[452377167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2348; }","duration":"189.269341ms","start":"2026-04-07T13:02:18.900137Z","end":"2026-04-07T13:02:19.089406Z","steps":["trace[452377167] 'agreement among raft nodes before linearized reading' (duration: 188.76358ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:19.773875Z","caller":"traceutil/trace.go:171","msg":"trace[982898445] transaction","detail":"{read_only:false; response_revision:2353; number_of_response:1; }","duration":"203.453392ms","start":"2026-04-07T13:02:19.57039Z","end":"2026-04-07T13:02:19.773843Z","steps":["trace[982898445] 'process raft request' (duration: 203.173316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:20.203028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.450067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T13:02:20.203359Z","caller":"traceutil/trace.go:171","msg":"trace[1335499488] transaction","detail":"{read_only:false; response_revision:2354; number_of_response:1; }","duration":"363.142784ms","start":"2026-04-07T13:02:19.840139Z","end":"2026-04-07T13:02:20.203282Z","steps":["trace[1335499488] 'process raft request' (duration: 175.801646ms)","trace[1335499488] 'compare' (duration: 185.66385ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:02:20.203312Z","caller":"traceutil/trace.go:171","msg":"trace[1265387847] linearizableReadLoop","detail":"{readStateIndex:2441; appliedIndex:2439; }","duration":"317.252893ms","start":"2026-04-07T13:02:19.886038Z","end":"2026-04-07T13:02:20.203291Z","steps":["trace[1265387847] 'read index received' (duration: 129.954497ms)","trace[1265387847] 'applied index is now lower than readState.Index' (duration: 187.296806ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:02:20.203451Z","caller":"traceutil/trace.go:171","msg":"trace[963556823] transaction","detail":"{read_only:false; response_revision:2355; number_of_response:1; }","duration":"333.131013ms","start":"2026-04-07T13:02:19.870274Z","end":"2026-04-07T13:02:20.203405Z","steps":["trace[963556823] 'process raft request' (duration: 332.897378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:20.203593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.52517ms","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":"warn","ts":"2026-04-07T13:02:20.203637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:19.840103Z","time spent":"363.38124ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:02:20.203669Z","caller":"traceutil/trace.go:171","msg":"trace[1559874669] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2355; }","duration":"317.669764ms","start":"2026-04-07T13:02:19.885983Z","end":"2026-04-07T13:02:20.203653Z","steps":["trace[1559874669] 'agreement among raft nodes before linearized reading' (duration: 317.51007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:20.203723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:19.885967Z","time spent":"317.741665ms","remote":"127.0.0.1:60824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:02:20.203807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:02:19.870238Z","time spent":"333.384329ms","remote":"127.0.0.1:60980","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-07T13:02:20.203822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.238652ms","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-07T13:02:20.203912Z","caller":"traceutil/trace.go:171","msg":"trace[624940377] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2355; }","duration":"105.355314ms","start":"2026-04-07T13:02:20.098515Z","end":"2026-04-07T13:02:20.20387Z","steps":["trace[624940377] 'agreement among raft nodes before linearized reading' (duration: 105.16381ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:56.49488Z","caller":"traceutil/trace.go:171","msg":"trace[597296345] linearizableReadLoop","detail":"{readStateIndex:2823; appliedIndex:2822; }","duration":"110.233665ms","start":"2026-04-07T13:02:56.384619Z","end":"2026-04-07T13:02:56.494853Z","steps":["trace[597296345] 'read index received' (duration: 110.134253ms)","trace[597296345] 'applied index is now lower than readState.Index' (duration: 97.732µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:02:56.494938Z","caller":"traceutil/trace.go:171","msg":"trace[1121656537] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"147.607795ms","start":"2026-04-07T13:02:56.347305Z","end":"2026-04-07T13:02:56.494913Z","steps":["trace[1121656537] 'process raft request' (duration: 147.382919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:56.495414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.736387ms","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-07T13:02:56.495542Z","caller":"traceutil/trace.go:171","msg":"trace[393813542] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2720; }","duration":"110.974843ms","start":"2026-04-07T13:02:56.384542Z","end":"2026-04-07T13:02:56.495517Z","steps":["trace[393813542] 'agreement among raft nodes before linearized reading' (duration: 110.448131ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:59.413786Z","caller":"traceutil/trace.go:171","msg":"trace[1537496127] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"163.528536ms","start":"2026-04-07T13:02:59.250225Z","end":"2026-04-07T13:02:59.413753Z","steps":["trace[1537496127] 'process raft request' (duration: 163.323002ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:59.414649Z","caller":"traceutil/trace.go:171","msg":"trace[1007584180] linearizableReadLoop","detail":"{readStateIndex:2844; appliedIndex:2844; }","duration":"110.784068ms","start":"2026-04-07T13:02:59.303853Z","end":"2026-04-07T13:02:59.414637Z","steps":["trace[1007584180] 'read index received' (duration: 110.778338ms)","trace[1007584180] 'applied index is now lower than readState.Index' (duration: 4.69µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:02:59.41493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.059875ms","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-07T13:02:59.415037Z","caller":"traceutil/trace.go:171","msg":"trace[1128059329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2741; }","duration":"111.247438ms","start":"2026-04-07T13:02:59.303758Z","end":"2026-04-07T13:02:59.415006Z","steps":["trace[1128059329] 'agreement among raft nodes before linearized reading' (duration: 110.953132ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:09.573754Z","caller":"traceutil/trace.go:171","msg":"trace[1633402264] linearizableReadLoop","detail":"{readStateIndex:2910; appliedIndex:2909; }","duration":"111.30435ms","start":"2026-04-07T13:03:09.462421Z","end":"2026-04-07T13:03:09.573725Z","steps":["trace[1633402264] 'read index received' (duration: 111.068885ms)","trace[1633402264] 'applied index is now lower than readState.Index' (duration: 234.665µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:03:09.573813Z","caller":"traceutil/trace.go:171","msg":"trace[157206611] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"117.779727ms","start":"2026-04-07T13:03:09.455998Z","end":"2026-04-07T13:03:09.573777Z","steps":["trace[157206611] 'process raft request' (duration: 117.564712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:03:09.574041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.589507ms","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-07T13:03:09.574077Z","caller":"traceutil/trace.go:171","msg":"trace[707121723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2805; }","duration":"111.705479ms","start":"2026-04-07T13:03:09.462364Z","end":"2026-04-07T13:03:09.57407Z","steps":["trace[707121723] 'agreement among raft nodes before linearized reading' (duration: 111.531045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:03:09.574363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.164524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-07T13:03:09.57441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.365472ms","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-07T13:03:09.574484Z","caller":"traceutil/trace.go:171","msg":"trace[1848094890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2805; }","duration":"108.465825ms","start":"2026-04-07T13:03:09.466001Z","end":"2026-04-07T13:03:09.574467Z","steps":["trace[1848094890] 'agreement among raft nodes before linearized reading' (duration: 108.167298ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:09.574481Z","caller":"traceutil/trace.go:171","msg":"trace[1456114384] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2805; }","duration":"110.341638ms","start":"2026-04-07T13:03:09.464116Z","end":"2026-04-07T13:03:09.574458Z","steps":["trace[1456114384] 'agreement among raft nodes before linearized reading' (duration: 109.816076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:03:33.403057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.999925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:03:33.403188Z","caller":"traceutil/trace.go:171","msg":"trace[408716281] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2946; }","duration":"179.20231ms","start":"2026-04-07T13:03:33.223958Z","end":"2026-04-07T13:03:33.40316Z","steps":["trace[408716281] 'count revisions from in-memory index tree' (duration: 178.916632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:03:33.40342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.98156ms","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-07T13:03:33.403543Z","caller":"traceutil/trace.go:171","msg":"trace[2040267636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2946; }","duration":"155.167654ms","start":"2026-04-07T13:03:33.248345Z","end":"2026-04-07T13:03:33.403513Z","steps":["trace[2040267636] 'range keys from in-memory index tree' (duration: 154.630961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:03:33.403513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.672632ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:03:33.403701Z","caller":"traceutil/trace.go:171","msg":"trace[1666476105] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2946; }","duration":"248.824636ms","start":"2026-04-07T13:03:33.154829Z","end":"2026-04-07T13:03:33.403654Z","steps":["trace[1666476105] 'range keys from in-memory index tree' (duration: 248.662372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:03:33.941056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.477669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T13:03:33.942476Z","caller":"traceutil/trace.go:171","msg":"trace[797805193] linearizableReadLoop","detail":"{readStateIndex:3058; appliedIndex:3057; }","duration":"252.920387ms","start":"2026-04-07T13:03:33.689523Z","end":"2026-04-07T13:03:33.942444Z","steps":["trace[797805193] 'read index received' (duration: 45.798782ms)","trace[797805193] 'applied index is now lower than readState.Index' (duration: 207.120025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:03:33.942682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.154873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:03:33.942682Z","caller":"traceutil/trace.go:171","msg":"trace[1341054767] transaction","detail":"{read_only:false; response_revision:2948; number_of_response:1; }","duration":"265.136597ms","start":"2026-04-07T13:03:33.677403Z","end":"2026-04-07T13:03:33.94254Z","steps":["trace[1341054767] 'process raft request' (duration: 58.027542ms)","trace[1341054767] 'compare' (duration: 205.271192ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:03:33.94279Z","caller":"traceutil/trace.go:171","msg":"trace[727977187] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2948; }","duration":"253.311047ms","start":"2026-04-07T13:03:33.689462Z","end":"2026-04-07T13:03:33.942773Z","steps":["trace[727977187] 'agreement among raft nodes before linearized reading' (duration: 253.165124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:03:33.942995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.091395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T13:03:33.943126Z","caller":"traceutil/trace.go:171","msg":"trace[1375592905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2948; }","duration":"236.272098ms","start":"2026-04-07T13:03:33.706826Z","end":"2026-04-07T13:03:33.943098Z","steps":["trace[1375592905] 'agreement among raft nodes before linearized reading' (duration: 235.984662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:03:33.943173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.687321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-07T13:03:33.943427Z","caller":"traceutil/trace.go:171","msg":"trace[1736869625] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2948; }","duration":"230.940067ms","start":"2026-04-07T13:03:33.712467Z","end":"2026-04-07T13:03:33.943407Z","steps":["trace[1736869625] 'agreement among raft nodes before linearized reading' (duration: 230.588429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:03:33.943492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.602126ms","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-07T13:03:33.943504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.915496ms","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-07T13:03:33.943557Z","caller":"traceutil/trace.go:171","msg":"trace[1319680013] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2948; }","duration":"236.716118ms","start":"2026-04-07T13:03:33.706826Z","end":"2026-04-07T13:03:33.943542Z","steps":["trace[1319680013] 'agreement among raft nodes before linearized reading' (duration: 236.291839ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:33.943613Z","caller":"traceutil/trace.go:171","msg":"trace[1784461193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2948; }","duration":"231.0692ms","start":"2026-04-07T13:03:33.712515Z","end":"2026-04-07T13:03:33.943584Z","steps":["trace[1784461193] 'agreement among raft nodes before linearized reading' (duration: 230.551068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:03:33.943865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.197894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:03:33.944027Z","caller":"traceutil/trace.go:171","msg":"trace[1259036430] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:2948; }","duration":"231.431129ms","start":"2026-04-07T13:03:33.712569Z","end":"2026-04-07T13:03:33.944001Z","steps":["trace[1259036430] 'agreement among raft nodes before linearized reading' (duration: 230.460557ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:34.116849Z","caller":"traceutil/trace.go:171","msg":"trace[1241887930] transaction","detail":"{read_only:false; response_revision:2949; number_of_response:1; }","duration":"167.749779ms","start":"2026-04-07T13:03:33.949058Z","end":"2026-04-07T13:03:34.116807Z","steps":["trace[1241887930] 'process raft request' (duration: 156.710777ms)","trace[1241887930] 'compare' (duration: 10.602652ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:03:34.219496Z","caller":"traceutil/trace.go:171","msg":"trace[1228306220] transaction","detail":"{read_only:false; response_revision:2950; number_of_response:1; }","duration":"270.394046ms","start":"2026-04-07T13:03:33.949063Z","end":"2026-04-07T13:03:34.219457Z","steps":["trace[1228306220] 'process raft request' (duration: 267.741385ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:34.219599Z","caller":"traceutil/trace.go:171","msg":"trace[1750635499] transaction","detail":"{read_only:false; response_revision:2952; number_of_response:1; }","duration":"270.392166ms","start":"2026-04-07T13:03:33.9492Z","end":"2026-04-07T13:03:34.219592Z","steps":["trace[1750635499] 'process raft request' (duration: 269.976016ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:34.219943Z","caller":"traceutil/trace.go:171","msg":"trace[581395230] transaction","detail":"{read_only:false; response_revision:2953; number_of_response:1; }","duration":"270.626001ms","start":"2026-04-07T13:03:33.949269Z","end":"2026-04-07T13:03:34.219895Z","steps":["trace[581395230] 'process raft request' (duration: 269.936046ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:34.21952Z","caller":"traceutil/trace.go:171","msg":"trace[1919229897] transaction","detail":"{read_only:false; response_revision:2951; number_of_response:1; }","duration":"270.323594ms","start":"2026-04-07T13:03:33.949169Z","end":"2026-04-07T13:03:34.219492Z","steps":["trace[1919229897] 'process raft request' (duration: 269.890985ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:16.571147Z","caller":"traceutil/trace.go:171","msg":"trace[1924352858] linearizableReadLoop","detail":"{readStateIndex:4149; appliedIndex:4148; }","duration":"270.852375ms","start":"2026-04-07T13:06:16.300266Z","end":"2026-04-07T13:06:16.571118Z","steps":["trace[1924352858] 'read index received' (duration: 270.660631ms)","trace[1924352858] 'applied index is now lower than readState.Index' (duration: 189.994µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:06:16.571172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:06:16.237566Z","time spent":"333.597907ms","remote":"127.0.0.1:60724","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-07T13:06:16.57133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.044589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:06:16.571406Z","caller":"traceutil/trace.go:171","msg":"trace[1819255089] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:4000; }","duration":"271.210123ms","start":"2026-04-07T13:06:16.300175Z","end":"2026-04-07T13:06:16.571385Z","steps":["trace[1819255089] 'agreement among raft nodes before linearized reading' (duration: 271.06427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:16.571371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.962755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:06:16.571469Z","caller":"traceutil/trace.go:171","msg":"trace[513769996] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:4000; }","duration":"172.135529ms","start":"2026-04-07T13:06:16.399322Z","end":"2026-04-07T13:06:16.571457Z","steps":["trace[513769996] 'agreement among raft nodes before linearized reading' (duration: 171.983615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:16.571563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.829327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:06:16.571483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.266297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T13:06:16.571625Z","caller":"traceutil/trace.go:171","msg":"trace[964461056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4000; }","duration":"154.910819ms","start":"2026-04-07T13:06:16.416698Z","end":"2026-04-07T13:06:16.571609Z","steps":["trace[964461056] 'agreement among raft nodes before linearized reading' (duration: 154.834707ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:16.571648Z","caller":"traceutil/trace.go:171","msg":"trace[1208157711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4000; }","duration":"144.438532ms","start":"2026-04-07T13:06:16.427187Z","end":"2026-04-07T13:06:16.571626Z","steps":["trace[1208157711] 'agreement among raft nodes before linearized reading' (duration: 144.139165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:16.571653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.657616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wrthh\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T13:06:16.571777Z","caller":"traceutil/trace.go:171","msg":"trace[84460293] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wrthh; range_end:; response_count:1; response_revision:4000; }","duration":"140.806619ms","start":"2026-04-07T13:06:16.430946Z","end":"2026-04-07T13:06:16.571753Z","steps":["trace[84460293] 'agreement among raft nodes before linearized reading' (duration: 140.435801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:22.184153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.131886ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T13:06:22.184921Z","caller":"traceutil/trace.go:171","msg":"trace[1637818876] linearizableReadLoop","detail":"{readStateIndex:4179; appliedIndex:4178; }","duration":"517.685027ms","start":"2026-04-07T13:06:21.667221Z","end":"2026-04-07T13:06:22.184906Z","steps":["trace[1637818876] 'read index received' (duration: 183.742313ms)","trace[1637818876] 'applied index is now lower than readState.Index' (duration: 333.941054ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:06:22.185063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.391766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:06:22.185118Z","caller":"traceutil/trace.go:171","msg":"trace[2026738404] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:4029; }","duration":"361.492138ms","start":"2026-04-07T13:06:21.823616Z","end":"2026-04-07T13:06:22.185108Z","steps":["trace[2026738404] 'agreement among raft nodes before linearized reading' (duration: 361.391486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:22.185178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:06:21.823597Z","time spent":"361.571001ms","remote":"127.0.0.1:56560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:06:22.185173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.933482ms","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-07T13:06:22.185283Z","caller":"traceutil/trace.go:171","msg":"trace[414974661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4029; }","duration":"518.093096ms","start":"2026-04-07T13:06:21.667165Z","end":"2026-04-07T13:06:22.185259Z","steps":["trace[414974661] 'agreement among raft nodes before linearized reading' (duration: 517.794409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:22.185326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:06:21.667145Z","time spent":"518.173178ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T13:07:51.670929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.161695ms","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-07T13:07:51.67108Z","caller":"traceutil/trace.go:171","msg":"trace[924203847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5210; }","duration":"190.3785ms","start":"2026-04-07T13:07:51.480675Z","end":"2026-04-07T13:07:51.671053Z","steps":["trace[924203847] 'range keys from in-memory index tree' (duration: 189.93723ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:07:51.788746Z","caller":"traceutil/trace.go:171","msg":"trace[1306241763] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"213.983035ms","start":"2026-04-07T13:07:51.574731Z","end":"2026-04-07T13:07:51.788714Z","steps":["trace[1306241763] 'process raft request' (duration: 213.809371ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:07:51.938169Z","caller":"traceutil/trace.go:171","msg":"trace[1112484616] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"262.152153ms","start":"2026-04-07T13:07:51.675963Z","end":"2026-04-07T13:07:51.938115Z","steps":["trace[1112484616] 'process raft request' (duration: 261.737904ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:07:51.938328Z","caller":"traceutil/trace.go:171","msg":"trace[347553578] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"218.172799ms","start":"2026-04-07T13:07:51.720104Z","end":"2026-04-07T13:07:51.938276Z","steps":["trace[347553578] 'process raft request' (duration: 217.881113ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:07:51.938499Z","caller":"traceutil/trace.go:171","msg":"trace[1697906265] linearizableReadLoop","detail":"{readStateIndex:5410; appliedIndex:5407; }","duration":"193.403128ms","start":"2026-04-07T13:07:51.745076Z","end":"2026-04-07T13:07:51.938479Z","steps":["trace[1697906265] 'read index received' (duration: 43.588247ms)","trace[1697906265] 'applied index is now lower than readState.Index' (duration: 149.81348ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:07:51.938574Z","caller":"traceutil/trace.go:171","msg":"trace[902991127] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"190.85363ms","start":"2026-04-07T13:07:51.747705Z","end":"2026-04-07T13:07:51.938558Z","steps":["trace[902991127] 'process raft request' (duration: 190.427011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:07:51.940389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.25006ms","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-07T13:07:51.940535Z","caller":"traceutil/trace.go:171","msg":"trace[1817234323] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5214; }","duration":"195.480655ms","start":"2026-04-07T13:07:51.745015Z","end":"2026-04-07T13:07:51.940496Z","steps":["trace[1817234323] 'agreement among raft nodes before linearized reading' (duration: 193.615533ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:07:51.966151Z","caller":"traceutil/trace.go:171","msg":"trace[1991956552] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"129.302707ms","start":"2026-04-07T13:07:51.836805Z","end":"2026-04-07T13:07:51.966108Z","steps":["trace[1991956552] 'process raft request' (duration: 129.071622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:07:51.966638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.436423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T13:07:51.96671Z","caller":"traceutil/trace.go:171","msg":"trace[570743338] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5217; }","duration":"119.564286ms","start":"2026-04-07T13:07:51.847127Z","end":"2026-04-07T13:07:51.966692Z","steps":["trace[570743338] 'agreement among raft nodes before linearized reading' (duration: 119.27626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:07:51.966644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.41859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T13:07:51.966869Z","caller":"traceutil/trace.go:171","msg":"trace[1162939481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5217; }","duration":"122.665196ms","start":"2026-04-07T13:07:51.844152Z","end":"2026-04-07T13:07:51.966817Z","steps":["trace[1162939481] 'agreement among raft nodes before linearized reading' (duration: 122.276917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:07:51.966909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.420548ms","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-07T13:07:51.96699Z","caller":"traceutil/trace.go:171","msg":"trace[351134214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5217; }","duration":"155.574572ms","start":"2026-04-07T13:07:51.811395Z","end":"2026-04-07T13:07:51.966969Z","steps":["trace[351134214] 'agreement among raft nodes before linearized reading' (duration: 155.286365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:07:53.388643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.271279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f92f36bc-41a7-4f8e-ba59-916ee69be3e0\" ","response":"range_response_count:1 size:3894"} {"level":"info","ts":"2026-04-07T13:07:53.388731Z","caller":"traceutil/trace.go:171","msg":"trace[992705419] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f92f36bc-41a7-4f8e-ba59-916ee69be3e0; range_end:; response_count:1; response_revision:5247; }","duration":"145.429783ms","start":"2026-04-07T13:07:53.243286Z","end":"2026-04-07T13:07:53.388716Z","steps":["trace[992705419] 'range keys from in-memory index tree' (duration: 145.070704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:08:02.449995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.287906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-07T13:08:02.450132Z","caller":"traceutil/trace.go:171","msg":"trace[1299973673] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5414; }","duration":"106.47689ms","start":"2026-04-07T13:08:02.343625Z","end":"2026-04-07T13:08:02.450102Z","steps":["trace[1299973673] 'range keys from in-memory index tree' (duration: 106.05442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:08:02.449992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.523164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:08:02.450331Z","caller":"traceutil/trace.go:171","msg":"trace[1687678431] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5414; }","duration":"118.918293ms","start":"2026-04-07T13:08:02.331393Z","end":"2026-04-07T13:08:02.450311Z","steps":["trace[1687678431] 'range keys from in-memory index tree' (duration: 118.415631ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:08:03.940798Z","caller":"traceutil/trace.go:171","msg":"trace[410969200] transaction","detail":"{read_only:false; response_revision:5445; number_of_response:1; }","duration":"267.345002ms","start":"2026-04-07T13:08:03.673403Z","end":"2026-04-07T13:08:03.940748Z","steps":["trace[410969200] 'process raft request' (duration: 266.997265ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:08:12.630781Z","caller":"traceutil/trace.go:171","msg":"trace[2073685060] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"174.689735ms","start":"2026-04-07T13:08:12.456025Z","end":"2026-04-07T13:08:12.630715Z","steps":["trace[2073685060] 'process raft request' (duration: 174.421589ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:08:12.863959Z","caller":"traceutil/trace.go:171","msg":"trace[229510694] linearizableReadLoop","detail":"{readStateIndex:5832; appliedIndex:5831; }","duration":"382.598552ms","start":"2026-04-07T13:08:12.481326Z","end":"2026-04-07T13:08:12.863924Z","steps":["trace[229510694] 'read index received' (duration: 149.808112ms)","trace[229510694] 'applied index is now lower than readState.Index' (duration: 232.78923ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:08:12.864079Z","caller":"traceutil/trace.go:171","msg":"trace[1697194484] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"405.06451ms","start":"2026-04-07T13:08:12.458964Z","end":"2026-04-07T13:08:12.864028Z","steps":["trace[1697194484] 'process raft request' (duration: 404.726533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:08:12.864224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.852077ms","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":"warn","ts":"2026-04-07T13:08:12.864282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.594115ms","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-07T13:08:12.864301Z","caller":"traceutil/trace.go:171","msg":"trace[1119609400] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5626; }","duration":"382.98616ms","start":"2026-04-07T13:08:12.481298Z","end":"2026-04-07T13:08:12.864284Z","steps":["trace[1119609400] 'agreement among raft nodes before linearized reading' (duration: 382.795045ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:08:12.864307Z","caller":"traceutil/trace.go:171","msg":"trace[2006520275] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5626; }","duration":"289.756599ms","start":"2026-04-07T13:08:12.574543Z","end":"2026-04-07T13:08:12.8643Z","steps":["trace[2006520275] 'agreement among raft nodes before linearized reading' (duration: 289.555415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:08:12.86431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:08:12.458937Z","time spent":"405.248784ms","remote":"127.0.0.1:60874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:08:12.864343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:08:12.481196Z","time spent":"383.136564ms","remote":"127.0.0.1:42258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:08:12.864567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.283323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:08:12.864688Z","caller":"traceutil/trace.go:171","msg":"trace[2108442525] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5626; }","duration":"101.758863ms","start":"2026-04-07T13:08:12.762899Z","end":"2026-04-07T13:08:12.864658Z","steps":["trace[2108442525] 'agreement among raft nodes before linearized reading' (duration: 101.244932ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:08:43.161821Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3000} {"level":"info","ts":"2026-04-07T13:08:43.237619Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3000,"took":"74.085497ms","hash":484822701,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":31936512,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T13:08:43.237697Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":484822701,"revision":3000,"compact-revision":-1} {"level":"info","ts":"2026-04-07T13:09:56.765628Z","caller":"traceutil/trace.go:171","msg":"trace[1552553238] transaction","detail":"{read_only:false; response_revision:6518; number_of_response:1; }","duration":"252.240274ms","start":"2026-04-07T13:09:56.513337Z","end":"2026-04-07T13:09:56.765577Z","steps":["trace[1552553238] 'process raft request' (duration: 214.061159ms)","trace[1552553238] 'compare' (duration: 37.901159ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:13:43.169319Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5921} {"level":"info","ts":"2026-04-07T13:13:43.250659Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5921,"took":"78.860992ms","hash":1308891147,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":25722880,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-07T13:13:43.250718Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1308891147,"revision":5921,"compact-revision":3000} {"level":"info","ts":"2026-04-07T13:14:22.502126Z","caller":"traceutil/trace.go:171","msg":"trace[2006080717] linearizableReadLoop","detail":"{readStateIndex:8782; appliedIndex:8781; }","duration":"291.113096ms","start":"2026-04-07T13:14:22.210978Z","end":"2026-04-07T13:14:22.502091Z","steps":["trace[2006080717] 'read index received' (duration: 290.786879ms)","trace[2006080717] 'applied index is now lower than readState.Index' (duration: 324.707µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:14:22.502293Z","caller":"traceutil/trace.go:171","msg":"trace[638623008] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"292.477246ms","start":"2026-04-07T13:14:22.209805Z","end":"2026-04-07T13:14:22.502283Z","steps":["trace[638623008] 'process raft request' (duration: 292.080337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:14:22.50262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.0353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T13:14:22.502679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.698479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T13:14:22.502739Z","caller":"traceutil/trace.go:171","msg":"trace[1112551092] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8485; }","duration":"291.816591ms","start":"2026-04-07T13:14:22.210907Z","end":"2026-04-07T13:14:22.502724Z","steps":["trace[1112551092] 'agreement among raft nodes before linearized reading' (duration: 291.656768ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:14:22.502725Z","caller":"traceutil/trace.go:171","msg":"trace[269492786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8485; }","duration":"290.204635ms","start":"2026-04-07T13:14:22.212495Z","end":"2026-04-07T13:14:22.502699Z","steps":["trace[269492786] 'agreement among raft nodes before linearized reading' (duration: 289.913658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:14:22.707073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.185837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:14:22.707178Z","caller":"traceutil/trace.go:171","msg":"trace[661839470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8485; }","duration":"203.32195ms","start":"2026-04-07T13:14:22.503832Z","end":"2026-04-07T13:14:22.707154Z","steps":["trace[661839470] 'range keys from in-memory index tree' (duration: 203.088124ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:14:22.761406Z","caller":"traceutil/trace.go:171","msg":"trace[1107265092] transaction","detail":"{read_only:false; response_revision:8486; number_of_response:1; }","duration":"253.267566ms","start":"2026-04-07T13:14:22.508096Z","end":"2026-04-07T13:14:22.761364Z","steps":["trace[1107265092] 'process raft request' (duration: 253.004469ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:14:22.762347Z","caller":"traceutil/trace.go:171","msg":"trace[1133299704] transaction","detail":"{read_only:false; response_revision:8487; number_of_response:1; }","duration":"254.152236ms","start":"2026-04-07T13:14:22.508169Z","end":"2026-04-07T13:14:22.762322Z","steps":["trace[1133299704] 'process raft request' (duration: 253.961061ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:14:22.762617Z","caller":"traceutil/trace.go:171","msg":"trace[38557120] transaction","detail":"{read_only:false; response_revision:8488; number_of_response:1; }","duration":"254.097295ms","start":"2026-04-07T13:14:22.508469Z","end":"2026-04-07T13:14:22.762566Z","steps":["trace[38557120] 'process raft request' (duration: 253.805819ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:14:22.762842Z","caller":"traceutil/trace.go:171","msg":"trace[1601589799] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"115.215978ms","start":"2026-04-07T13:14:22.647607Z","end":"2026-04-07T13:14:22.762823Z","steps":["trace[1601589799] 'process raft request' (duration: 114.86968ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:14:33.809216Z","caller":"traceutil/trace.go:171","msg":"trace[934382872] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"147.308647ms","start":"2026-04-07T13:14:33.661873Z","end":"2026-04-07T13:14:33.809182Z","steps":["trace[934382872] 'process raft request' (duration: 146.196702ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:14:33.968382Z","caller":"traceutil/trace.go:171","msg":"trace[925134743] linearizableReadLoop","detail":"{readStateIndex:8848; appliedIndex:8847; }","duration":"147.624205ms","start":"2026-04-07T13:14:33.820731Z","end":"2026-04-07T13:14:33.968355Z","steps":["trace[925134743] 'read index received' (duration: 98.666113ms)","trace[925134743] 'applied index is now lower than readState.Index' (duration: 48.956952ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:14:33.968409Z","caller":"traceutil/trace.go:171","msg":"trace[732936740] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"282.556251ms","start":"2026-04-07T13:14:33.68581Z","end":"2026-04-07T13:14:33.968366Z","steps":["trace[732936740] 'process raft request' (duration: 233.60428ms)","trace[732936740] 'compare' (duration: 48.731336ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:14:33.968643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.241365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:14:33.968617Z","caller":"traceutil/trace.go:171","msg":"trace[1455656732] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"139.526391ms","start":"2026-04-07T13:14:33.829039Z","end":"2026-04-07T13:14:33.968565Z","steps":["trace[1455656732] 'process raft request' (duration: 139.271185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:14:33.968703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.955512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:14:33.968689Z","caller":"traceutil/trace.go:171","msg":"trace[896172414] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:8549; }","duration":"132.365867ms","start":"2026-04-07T13:14:33.83631Z","end":"2026-04-07T13:14:33.968676Z","steps":["trace[896172414] 'agreement among raft nodes before linearized reading' (duration: 132.220474ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:14:33.968794Z","caller":"traceutil/trace.go:171","msg":"trace[1654414758] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8549; }","duration":"148.114456ms","start":"2026-04-07T13:14:33.820657Z","end":"2026-04-07T13:14:33.968772Z","steps":["trace[1654414758] 'agreement among raft nodes before linearized reading' (duration: 147.81316ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:14:34.783162Z","caller":"traceutil/trace.go:171","msg":"trace[956761656] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"219.943799ms","start":"2026-04-07T13:14:34.563168Z","end":"2026-04-07T13:14:34.783112Z","steps":["trace[956761656] 'process raft request' (duration: 219.758164ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:14:35.074774Z","caller":"traceutil/trace.go:171","msg":"trace[298481765] linearizableReadLoop","detail":"{readStateIndex:8851; appliedIndex:8850; }","duration":"251.942116ms","start":"2026-04-07T13:14:34.822795Z","end":"2026-04-07T13:14:35.074737Z","steps":["trace[298481765] 'read index received' (duration: 217.747749ms)","trace[298481765] 'applied index is now lower than readState.Index' (duration: 34.192757ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:14:35.074793Z","caller":"traceutil/trace.go:171","msg":"trace[1849662731] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"258.669698ms","start":"2026-04-07T13:14:34.816075Z","end":"2026-04-07T13:14:35.074745Z","steps":["trace[1849662731] 'process raft request' (duration: 224.45658ms)","trace[1849662731] 'compare' (duration: 33.997843ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:14:35.075017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.211841ms","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-07T13:14:35.075092Z","caller":"traceutil/trace.go:171","msg":"trace[1543192659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8551; }","duration":"252.356385ms","start":"2026-04-07T13:14:34.822718Z","end":"2026-04-07T13:14:35.075074Z","steps":["trace[1543192659] 'agreement among raft nodes before linearized reading' (duration: 252.11085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:14:35.075126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.006399ms","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-07T13:14:35.075181Z","caller":"traceutil/trace.go:171","msg":"trace[169755979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8551; }","duration":"238.102771ms","start":"2026-04-07T13:14:34.837062Z","end":"2026-04-07T13:14:35.075165Z","steps":["trace[169755979] 'agreement among raft nodes before linearized reading' (duration: 237.881976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:14:35.075292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.371035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T13:14:35.075367Z","caller":"traceutil/trace.go:171","msg":"trace[113422711] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8551; }","duration":"234.491428ms","start":"2026-04-07T13:14:34.840856Z","end":"2026-04-07T13:14:35.075347Z","steps":["trace[113422711] 'agreement among raft nodes before linearized reading' (duration: 234.244953ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:14:36.101052Z","caller":"traceutil/trace.go:171","msg":"trace[945534741] transaction","detail":"{read_only:false; response_revision:8559; number_of_response:1; }","duration":"125.298956ms","start":"2026-04-07T13:14:35.975718Z","end":"2026-04-07T13:14:36.101017Z","steps":["trace[945534741] 'process raft request' (duration: 123.19469ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:14:36.101051Z","caller":"traceutil/trace.go:171","msg":"trace[1446327523] linearizableReadLoop","detail":"{readStateIndex:8859; appliedIndex:8858; }","duration":"118.78723ms","start":"2026-04-07T13:14:35.982233Z","end":"2026-04-07T13:14:36.10102Z","steps":["trace[1446327523] 'read index received' (duration: 90.801414ms)","trace[1446327523] 'applied index is now lower than readState.Index' (duration: 27.984565ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:14:36.101275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.015204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wrthh\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T13:14:36.101328Z","caller":"traceutil/trace.go:171","msg":"trace[505295316] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wrthh; range_end:; response_count:1; response_revision:8559; }","duration":"119.092536ms","start":"2026-04-07T13:14:35.982224Z","end":"2026-04-07T13:14:36.101317Z","steps":["trace[505295316] 'agreement among raft nodes before linearized reading' (duration: 118.890132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:14:36.390077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.236\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T13:14:36.390207Z","caller":"traceutil/trace.go:171","msg":"trace[848546052] range","detail":"{range_begin:/registry/masterleases/199.19.213.236; range_end:; response_count:1; response_revision:8559; }","duration":"129.153805ms","start":"2026-04-07T13:14:36.261027Z","end":"2026-04-07T13:14:36.390181Z","steps":["trace[848546052] 'range keys from in-memory index tree' (duration: 128.462458ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:04.110284Z","caller":"traceutil/trace.go:171","msg":"trace[56480920] transaction","detail":"{read_only:false; response_revision:8761; number_of_response:1; }","duration":"289.567391ms","start":"2026-04-07T13:15:03.820679Z","end":"2026-04-07T13:15:04.110247Z","steps":["trace[56480920] 'process raft request' (duration: 289.332456ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:04.357826Z","caller":"traceutil/trace.go:171","msg":"trace[1953519507] transaction","detail":"{read_only:false; response_revision:8763; number_of_response:1; }","duration":"178.749602ms","start":"2026-04-07T13:15:04.179052Z","end":"2026-04-07T13:15:04.357801Z","steps":["trace[1953519507] 'process raft request' (duration: 178.66413ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:04.358135Z","caller":"traceutil/trace.go:171","msg":"trace[1988608588] transaction","detail":"{read_only:false; response_revision:8762; number_of_response:1; }","duration":"518.990193ms","start":"2026-04-07T13:15:03.839134Z","end":"2026-04-07T13:15:04.358124Z","steps":["trace[1988608588] 'process raft request' (duration: 503.86538ms)","trace[1988608588] 'compare' (duration: 14.378777ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:15:04.358267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:15:03.839098Z","time spent":"519.066575ms","remote":"127.0.0.1:60980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:15:04.358395Z","caller":"traceutil/trace.go:171","msg":"trace[492032779] transaction","detail":"{read_only:false; response_revision:8764; number_of_response:1; }","duration":"148.465814ms","start":"2026-04-07T13:15:04.209907Z","end":"2026-04-07T13:15:04.358373Z","steps":["trace[492032779] 'process raft request' (duration: 147.85688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:15:07.583336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.822518ms","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-07T13:15:07.583416Z","caller":"traceutil/trace.go:171","msg":"trace[19958343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8779; }","duration":"257.959303ms","start":"2026-04-07T13:15:07.325438Z","end":"2026-04-07T13:15:07.583397Z","steps":["trace[19958343] 'range keys from in-memory index tree' (duration: 257.097382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:15:07.583516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.15771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-07T13:15:07.583545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.971961ms","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-07T13:15:07.583613Z","caller":"traceutil/trace.go:171","msg":"trace[1659983659] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8779; }","duration":"226.501218ms","start":"2026-04-07T13:15:07.357087Z","end":"2026-04-07T13:15:07.583589Z","steps":["trace[1659983659] 'range keys from in-memory index tree' (duration: 225.906683ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:07.58364Z","caller":"traceutil/trace.go:171","msg":"trace[1842933064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8779; }","duration":"169.129544ms","start":"2026-04-07T13:15:07.414491Z","end":"2026-04-07T13:15:07.583621Z","steps":["trace[1842933064] 'range keys from in-memory index tree' (duration: 168.202652ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:07.725939Z","caller":"traceutil/trace.go:171","msg":"trace[2085514545] transaction","detail":"{read_only:false; response_revision:8780; number_of_response:1; }","duration":"138.008606ms","start":"2026-04-07T13:15:07.587901Z","end":"2026-04-07T13:15:07.72591Z","steps":["trace[2085514545] 'process raft request' (duration: 137.824672ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:07.82079Z","caller":"traceutil/trace.go:171","msg":"trace[252578517] transaction","detail":"{read_only:false; response_revision:8782; number_of_response:1; }","duration":"231.276555ms","start":"2026-04-07T13:15:07.589488Z","end":"2026-04-07T13:15:07.820764Z","steps":["trace[252578517] 'process raft request' (duration: 231.207893ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:07.820936Z","caller":"traceutil/trace.go:171","msg":"trace[1336385742] transaction","detail":"{read_only:false; response_revision:8781; number_of_response:1; }","duration":"232.681497ms","start":"2026-04-07T13:15:07.588194Z","end":"2026-04-07T13:15:07.820876Z","steps":["trace[1336385742] 'process raft request' (duration: 226.411925ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:17.174131Z","caller":"traceutil/trace.go:171","msg":"trace[2057284409] linearizableReadLoop","detail":"{readStateIndex:9185; appliedIndex:9184; }","duration":"108.415247ms","start":"2026-04-07T13:15:17.065681Z","end":"2026-04-07T13:15:17.174097Z","steps":["trace[2057284409] 'read index received' (duration: 108.152901ms)","trace[2057284409] 'applied index is now lower than readState.Index' (duration: 261.106µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:15:17.174263Z","caller":"traceutil/trace.go:171","msg":"trace[1955340214] transaction","detail":"{read_only:false; response_revision:8871; number_of_response:1; }","duration":"113.919041ms","start":"2026-04-07T13:15:17.060285Z","end":"2026-04-07T13:15:17.174204Z","steps":["trace[1955340214] 'process raft request' (duration: 113.620724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:15:17.17442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.636951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:15:17.174544Z","caller":"traceutil/trace.go:171","msg":"trace[329790033] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:8871; }","duration":"108.870547ms","start":"2026-04-07T13:15:17.065645Z","end":"2026-04-07T13:15:17.174515Z","steps":["trace[329790033] 'agreement among raft nodes before linearized reading' (duration: 108.584411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:15:17.475736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.679463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-nhtlg\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-04-07T13:15:17.475838Z","caller":"traceutil/trace.go:171","msg":"trace[194664853] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-nhtlg; range_end:; response_count:1; response_revision:8871; }","duration":"228.853406ms","start":"2026-04-07T13:15:17.246963Z","end":"2026-04-07T13:15:17.475816Z","steps":["trace[194664853] 'range keys from in-memory index tree' (duration: 228.437977ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:17.492382Z","caller":"traceutil/trace.go:171","msg":"trace[209886066] transaction","detail":"{read_only:false; response_revision:8872; number_of_response:1; }","duration":"244.313237ms","start":"2026-04-07T13:15:17.248034Z","end":"2026-04-07T13:15:17.492347Z","steps":["trace[209886066] 'process raft request' (duration: 244.096292ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:17.55288Z","caller":"traceutil/trace.go:171","msg":"trace[1423671568] linearizableReadLoop","detail":"{readStateIndex:9187; appliedIndex:9185; }","duration":"262.599483ms","start":"2026-04-07T13:15:17.290235Z","end":"2026-04-07T13:15:17.552835Z","steps":["trace[1423671568] 'read index received' (duration: 201.869702ms)","trace[1423671568] 'applied index is now lower than readState.Index' (duration: 60.728571ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:15:17.553167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.902649ms","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-07T13:15:17.553035Z","caller":"traceutil/trace.go:171","msg":"trace[225958741] transaction","detail":"{read_only:false; response_revision:8873; number_of_response:1; }","duration":"293.373203ms","start":"2026-04-07T13:15:17.259605Z","end":"2026-04-07T13:15:17.552978Z","steps":["trace[225958741] 'process raft request' (duration: 292.951844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:15:17.553267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.677168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:15:17.553213Z","caller":"traceutil/trace.go:171","msg":"trace[2068869142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8874; }","duration":"262.998242ms","start":"2026-04-07T13:15:17.290202Z","end":"2026-04-07T13:15:17.553201Z","steps":["trace[2068869142] 'agreement among raft nodes before linearized reading' (duration: 262.814248ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:17.553385Z","caller":"traceutil/trace.go:171","msg":"trace[762573983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8874; }","duration":"136.873924ms","start":"2026-04-07T13:15:17.416484Z","end":"2026-04-07T13:15:17.553357Z","steps":["trace[762573983] 'agreement among raft nodes before linearized reading' (duration: 136.643807ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:17.887596Z","caller":"traceutil/trace.go:171","msg":"trace[1414591544] linearizableReadLoop","detail":"{readStateIndex:9191; appliedIndex:9189; }","duration":"112.12773ms","start":"2026-04-07T13:15:17.775431Z","end":"2026-04-07T13:15:17.887559Z","steps":["trace[1414591544] 'read index received' (duration: 29.261465ms)","trace[1414591544] 'applied index is now lower than readState.Index' (duration: 82.864955ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:15:17.887667Z","caller":"traceutil/trace.go:171","msg":"trace[1739757462] transaction","detail":"{read_only:false; response_revision:8877; number_of_response:1; }","duration":"133.811374ms","start":"2026-04-07T13:15:17.753803Z","end":"2026-04-07T13:15:17.887615Z","steps":["trace[1739757462] 'process raft request' (duration: 133.587479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:15:17.887948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.497159ms","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-07T13:15:17.888005Z","caller":"traceutil/trace.go:171","msg":"trace[1593531656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8877; }","duration":"112.612142ms","start":"2026-04-07T13:15:17.775379Z","end":"2026-04-07T13:15:17.887991Z","steps":["trace[1593531656] 'agreement among raft nodes before linearized reading' (duration: 112.375686ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:18.600423Z","caller":"traceutil/trace.go:171","msg":"trace[1581839335] transaction","detail":"{read_only:false; response_revision:8883; number_of_response:1; }","duration":"222.44166ms","start":"2026-04-07T13:15:18.37794Z","end":"2026-04-07T13:15:18.600382Z","steps":["trace[1581839335] 'process raft request' (duration: 222.171014ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:18.6869Z","caller":"traceutil/trace.go:171","msg":"trace[1367325535] transaction","detail":"{read_only:false; response_revision:8885; number_of_response:1; }","duration":"278.787961ms","start":"2026-04-07T13:15:18.408083Z","end":"2026-04-07T13:15:18.686871Z","steps":["trace[1367325535] 'process raft request' (duration: 278.685408ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:18.686914Z","caller":"traceutil/trace.go:171","msg":"trace[380426640] transaction","detail":"{read_only:false; response_revision:8884; number_of_response:1; }","duration":"281.426812ms","start":"2026-04-07T13:15:18.40544Z","end":"2026-04-07T13:15:18.686867Z","steps":["trace[380426640] 'process raft request' (duration: 281.084734ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:18.687218Z","caller":"traceutil/trace.go:171","msg":"trace[1741462226] transaction","detail":"{read_only:false; response_revision:8886; number_of_response:1; }","duration":"220.777922ms","start":"2026-04-07T13:15:18.466385Z","end":"2026-04-07T13:15:18.687163Z","steps":["trace[1741462226] 'process raft request' (duration: 220.440835ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:18.687497Z","caller":"traceutil/trace.go:171","msg":"trace[1682692321] linearizableReadLoop","detail":"{readStateIndex:9199; appliedIndex:9196; }","duration":"230.628426ms","start":"2026-04-07T13:15:18.456473Z","end":"2026-04-07T13:15:18.687101Z","steps":["trace[1682692321] 'read index received' (duration: 143.674908ms)","trace[1682692321] 'applied index is now lower than readState.Index' (duration: 86.951108ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:15:18.687552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.067435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wrthh\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T13:15:18.687611Z","caller":"traceutil/trace.go:171","msg":"trace[1302768393] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wrthh; range_end:; response_count:1; response_revision:8886; }","duration":"231.181658ms","start":"2026-04-07T13:15:18.456414Z","end":"2026-04-07T13:15:18.687596Z","steps":["trace[1302768393] 'agreement among raft nodes before linearized reading' (duration: 230.873991ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:15:51.811383Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000253e.wal"} {"level":"info","ts":"2026-04-07T13:16:29.104137Z","caller":"traceutil/trace.go:171","msg":"trace[1296832510] transaction","detail":"{read_only:false; response_revision:9593; number_of_response:1; }","duration":"130.004888ms","start":"2026-04-07T13:16:28.974103Z","end":"2026-04-07T13:16:29.104108Z","steps":["trace[1296832510] 'process raft request' (duration: 129.781773ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:16:29.105334Z","caller":"traceutil/trace.go:171","msg":"trace[1475102192] transaction","detail":"{read_only:false; response_revision:9594; number_of_response:1; }","duration":"128.186395ms","start":"2026-04-07T13:16:28.97712Z","end":"2026-04-07T13:16:29.105306Z","steps":["trace[1475102192] 'process raft request' (duration: 127.638433ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:16:41.990957Z","caller":"traceutil/trace.go:171","msg":"trace[171996304] transaction","detail":"{read_only:false; response_revision:9665; number_of_response:1; }","duration":"102.507422ms","start":"2026-04-07T13:16:41.888412Z","end":"2026-04-07T13:16:41.99092Z","steps":["trace[171996304] 'process raft request' (duration: 66.508573ms)","trace[171996304] 'compare' (duration: 35.704713ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:16:41.991182Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f20dfa43a2e4607","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T13:16:42.112575Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T13:16:42.112868Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T13:18:14.225581Z","caller":"traceutil/trace.go:171","msg":"trace[537268552] transaction","detail":"{read_only:false; response_revision:10310; number_of_response:1; }","duration":"122.084066ms","start":"2026-04-07T13:18:14.103451Z","end":"2026-04-07T13:18:14.225535Z","steps":["trace[537268552] 'process raft request' (duration: 121.287418ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:43.175772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8101} {"level":"info","ts":"2026-04-07T13:18:43.240205Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8101,"took":"61.879017ms","hash":2658480051,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":28438528,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-07T13:18:43.240282Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2658480051,"revision":8101,"compact-revision":5921} {"level":"info","ts":"2026-04-07T13:20:41.93264Z","caller":"traceutil/trace.go:171","msg":"trace[1132470735] transaction","detail":"{read_only:false; response_revision:11221; number_of_response:1; }","duration":"131.9788ms","start":"2026-04-07T13:20:41.800628Z","end":"2026-04-07T13:20:41.932607Z","steps":["trace[1132470735] 'process raft request' (duration: 131.910848ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:20:41.932694Z","caller":"traceutil/trace.go:171","msg":"trace[27702979] transaction","detail":"{read_only:false; response_revision:11220; number_of_response:1; }","duration":"132.946152ms","start":"2026-04-07T13:20:41.799703Z","end":"2026-04-07T13:20:41.932649Z","steps":["trace[27702979] 'process raft request' (duration: 107.620706ms)","trace[27702979] 'compare' (duration: 25.05052ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:22:25.536509Z","caller":"traceutil/trace.go:171","msg":"trace[195710166] linearizableReadLoop","detail":"{readStateIndex:12235; appliedIndex:12234; }","duration":"103.507263ms","start":"2026-04-07T13:22:25.432975Z","end":"2026-04-07T13:22:25.536482Z","steps":["trace[195710166] 'read index received' (duration: 102.979691ms)","trace[195710166] 'applied index is now lower than readState.Index' (duration: 526.332µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:22:25.536569Z","caller":"traceutil/trace.go:171","msg":"trace[1316456763] transaction","detail":"{read_only:false; response_revision:11824; number_of_response:1; }","duration":"108.274152ms","start":"2026-04-07T13:22:25.428244Z","end":"2026-04-07T13:22:25.536518Z","steps":["trace[1316456763] 'process raft request' (duration: 107.73225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:22:25.53671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.702837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:22:25.536804Z","caller":"traceutil/trace.go:171","msg":"trace[567768751] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:11824; }","duration":"103.865061ms","start":"2026-04-07T13:22:25.43292Z","end":"2026-04-07T13:22:25.536785Z","steps":["trace[567768751] 'agreement among raft nodes before linearized reading' (duration: 103.676367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:22:25.787192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.910951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-07T13:22:25.787247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.947474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5127"} {"level":"info","ts":"2026-04-07T13:22:25.787314Z","caller":"traceutil/trace.go:171","msg":"trace[2025364930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11824; }","duration":"122.115176ms","start":"2026-04-07T13:22:25.665173Z","end":"2026-04-07T13:22:25.787288Z","steps":["trace[2025364930] 'range keys from in-memory index tree' (duration: 121.589834ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:22:25.787358Z","caller":"traceutil/trace.go:171","msg":"trace[1053630765] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:11824; }","duration":"119.081897ms","start":"2026-04-07T13:22:25.668248Z","end":"2026-04-07T13:22:25.78733Z","steps":["trace[1053630765] 'range keys from in-memory index tree' (duration: 118.699568ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:22:26.914056Z","caller":"traceutil/trace.go:171","msg":"trace[1393342118] linearizableReadLoop","detail":"{readStateIndex:12243; appliedIndex:12242; }","duration":"161.263885ms","start":"2026-04-07T13:22:26.752761Z","end":"2026-04-07T13:22:26.914024Z","steps":["trace[1393342118] 'read index received' (duration: 161.089101ms)","trace[1393342118] 'applied index is now lower than readState.Index' (duration: 173.744µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:22:26.91423Z","caller":"traceutil/trace.go:171","msg":"trace[329223731] transaction","detail":"{read_only:false; response_revision:11831; number_of_response:1; }","duration":"179.839849ms","start":"2026-04-07T13:22:26.73435Z","end":"2026-04-07T13:22:26.91419Z","steps":["trace[329223731] 'process raft request' (duration: 179.45818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:22:26.914312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.409521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T13:22:26.914506Z","caller":"traceutil/trace.go:171","msg":"trace[2108816529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11831; }","duration":"180.667568ms","start":"2026-04-07T13:22:26.733818Z","end":"2026-04-07T13:22:26.914485Z","steps":["trace[2108816529] 'agreement among raft nodes before linearized reading' (duration: 180.32226ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:22:26.963556Z","caller":"traceutil/trace.go:171","msg":"trace[362970710] transaction","detail":"{read_only:false; response_revision:11832; number_of_response:1; }","duration":"205.932301ms","start":"2026-04-07T13:22:26.757597Z","end":"2026-04-07T13:22:26.96353Z","steps":["trace[362970710] 'process raft request' (duration: 205.780077ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:22:27.495986Z","caller":"traceutil/trace.go:171","msg":"trace[1835761983] transaction","detail":"{read_only:false; response_revision:11834; number_of_response:1; }","duration":"149.312914ms","start":"2026-04-07T13:22:27.346644Z","end":"2026-04-07T13:22:27.495957Z","steps":["trace[1835761983] 'process raft request' (duration: 149.099499ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:22:32.964899Z","caller":"traceutil/trace.go:171","msg":"trace[1559393350] transaction","detail":"{read_only:false; response_revision:11862; number_of_response:1; }","duration":"115.26349ms","start":"2026-04-07T13:22:32.849591Z","end":"2026-04-07T13:22:32.964854Z","steps":["trace[1559393350] 'process raft request' (duration: 115.020935ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:22:33.123357Z","caller":"traceutil/trace.go:171","msg":"trace[921211763] transaction","detail":"{read_only:false; response_revision:11863; number_of_response:1; }","duration":"102.311206ms","start":"2026-04-07T13:22:33.021002Z","end":"2026-04-07T13:22:33.123313Z","steps":["trace[921211763] 'process raft request' (duration: 49.733961ms)","trace[921211763] 'compare' (duration: 52.382641ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:23:25.587149Z","caller":"traceutil/trace.go:171","msg":"trace[2105491806] transaction","detail":"{read_only:false; response_revision:12135; number_of_response:1; }","duration":"108.666111ms","start":"2026-04-07T13:23:25.478441Z","end":"2026-04-07T13:23:25.587108Z","steps":["trace[2105491806] 'process raft request' (duration: 79.297822ms)","trace[2105491806] 'compare' (duration: 29.181994ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:23:25.589501Z","caller":"traceutil/trace.go:171","msg":"trace[259324317] linearizableReadLoop","detail":"{readStateIndex:12560; appliedIndex:12558; }","duration":"104.692151ms","start":"2026-04-07T13:23:25.484782Z","end":"2026-04-07T13:23:25.589474Z","steps":["trace[259324317] 'read index received' (duration: 72.954438ms)","trace[259324317] 'applied index is now lower than readState.Index' (duration: 31.736253ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:23:25.589656Z","caller":"traceutil/trace.go:171","msg":"trace[1495692137] transaction","detail":"{read_only:false; response_revision:12136; number_of_response:1; }","duration":"107.637077ms","start":"2026-04-07T13:23:25.482003Z","end":"2026-04-07T13:23:25.58964Z","steps":["trace[1495692137] 'process raft request' (duration: 107.30604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:23:25.590025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.223912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T13:23:25.590148Z","caller":"traceutil/trace.go:171","msg":"trace[226218359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12136; }","duration":"105.399256ms","start":"2026-04-07T13:23:25.484727Z","end":"2026-04-07T13:23:25.590126Z","steps":["trace[226218359] 'agreement among raft nodes before linearized reading' (duration: 105.092199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:23:35.634948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.275302ms","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-07T13:23:35.635219Z","caller":"traceutil/trace.go:171","msg":"trace[1649017212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12186; }","duration":"108.618419ms","start":"2026-04-07T13:23:35.526563Z","end":"2026-04-07T13:23:35.635182Z","steps":["trace[1649017212] 'range keys from in-memory index tree' (duration: 108.019606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:23:35.635015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.847665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:23:35.635499Z","caller":"traceutil/trace.go:171","msg":"trace[584721341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12186; }","duration":"219.374897ms","start":"2026-04-07T13:23:35.416051Z","end":"2026-04-07T13:23:35.635426Z","steps":["trace[584721341] 'range keys from in-memory index tree' (duration: 218.733222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:23:41.724141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.669962ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T13:23:41.884988Z","caller":"traceutil/trace.go:171","msg":"trace[726302635] transaction","detail":"{read_only:false; response_revision:12217; number_of_response:1; }","duration":"135.863219ms","start":"2026-04-07T13:23:41.749092Z","end":"2026-04-07T13:23:41.884956Z","steps":["trace[726302635] 'process raft request' (duration: 135.611674ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:23:43.202232Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10511} {"level":"info","ts":"2026-04-07T13:23:43.4979Z","caller":"traceutil/trace.go:171","msg":"trace[974908781] transaction","detail":"{read_only:false; response_revision:12227; number_of_response:1; }","duration":"110.066332ms","start":"2026-04-07T13:23:43.387801Z","end":"2026-04-07T13:23:43.497867Z","steps":["trace[974908781] 'process raft request' (duration: 56.284009ms)","trace[974908781] 'compare' (duration: 48.219526ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:23:43.536904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10511,"took":"331.641428ms","hash":2744531225,"current-db-size-bytes":56107008,"current-db-size":"56 MB","current-db-size-in-use-bytes":21934080,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-07T13:23:43.537039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2744531225,"revision":10511,"compact-revision":8101} {"level":"info","ts":"2026-04-07T13:24:27.682424Z","caller":"traceutil/trace.go:171","msg":"trace[88015500] transaction","detail":"{read_only:false; response_revision:12453; number_of_response:1; }","duration":"154.985313ms","start":"2026-04-07T13:24:27.527413Z","end":"2026-04-07T13:24:27.682399Z","steps":["trace[88015500] 'process raft request' (duration: 154.822469ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:24:27.685847Z","caller":"traceutil/trace.go:171","msg":"trace[9504511] transaction","detail":"{read_only:false; response_revision:12454; number_of_response:1; }","duration":"144.393621ms","start":"2026-04-07T13:24:27.541428Z","end":"2026-04-07T13:24:27.685822Z","steps":["trace[9504511] 'process raft request' (duration: 144.264138ms)"],"step_count":1}