{"level":"warn","ts":"2026-04-27T20:58:41.755022Z","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-27T20:58:41.755138Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-27T20:58:41.755234Z","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-27T20:58:41.755252Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-04-27T20:58:41.755277Z","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-27T20:58:41.756208Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-04-27T20:58:41.756311Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-27T20:58:41.762403Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.245841ms"} {"level":"info","ts":"2026-04-27T20:58:41.771161Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-04-27T20:58:41.771288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T20:58:41.771319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-04-27T20:58:41.771334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T20:58:41.771351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-04-27T20:58:41.771396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-04-27T20:58:41.776763Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T20:58:41.778178Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T20:58:41.780668Z","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-27T20:58:41.782677Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T20:58:41.782902Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T20:58:41.783163Z","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-27T20:58:41.783293Z","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-27T20:58:41.78331Z","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-27T20:58:41.783915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-04-27T20:58:41.784028Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-04-27T20:58:41.787523Z","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-27T20:58:41.787727Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-04-27T20:58:41.787792Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-04-27T20:58:41.787909Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T20:58:41.787946Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T20:58:42.772563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T20:58:42.77263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T20:58:42.772659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-04-27T20:58:42.772675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-04-27T20:58:42.772683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-04-27T20:58:42.772696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-04-27T20:58:42.772706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-04-27T20:58:42.773477Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:58:42.775009Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:58:42.77508Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:58:42.775103Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:58:42.775121Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T20:58:42.775135Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T20:58:42.775144Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T20:58:42.776234Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T20:58:42.776344Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T20:58:42.778065Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-04-27T20:58:42.778379Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T20:59:20.598699Z","caller":"traceutil/trace.go:171","msg":"trace[974024506] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"113.037751ms","start":"2026-04-27T20:59:20.485625Z","end":"2026-04-27T20:59:20.598663Z","steps":["trace[974024506] 'read index received' (duration: 109.885163ms)","trace[974024506] 'applied index is now lower than readState.Index' (duration: 3.151407ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:59:20.600314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.029077ms","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-27T20:59:20.600493Z","caller":"traceutil/trace.go:171","msg":"trace[1759623783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"110.276464ms","start":"2026-04-27T20:59:20.490181Z","end":"2026-04-27T20:59:20.600457Z","steps":["trace[1759623783] 'agreement among raft nodes before linearized reading' (duration: 109.893773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:59:20.600514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.832442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T20:59:20.599401Z","caller":"traceutil/trace.go:171","msg":"trace[1908149145] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"206.609649ms","start":"2026-04-27T20:59:20.392506Z","end":"2026-04-27T20:59:20.599116Z","steps":["trace[1908149145] 'process raft request' (duration: 204.047837ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:59:20.600913Z","caller":"traceutil/trace.go:171","msg":"trace[1118717065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"115.277893ms","start":"2026-04-27T20:59:20.48558Z","end":"2026-04-27T20:59:20.600858Z","steps":["trace[1118717065] 'agreement among raft nodes before linearized reading' (duration: 113.281487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:59:20.600952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.530242ms","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-27T20:59:20.601157Z","caller":"traceutil/trace.go:171","msg":"trace[427027027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"100.710437ms","start":"2026-04-27T20:59:20.500343Z","end":"2026-04-27T20:59:20.601053Z","steps":["trace[427027027] 'agreement among raft nodes before linearized reading' (duration: 100.106011ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:59:20.821319Z","caller":"traceutil/trace.go:171","msg":"trace[50112092] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"156.157984ms","start":"2026-04-27T20:59:20.665131Z","end":"2026-04-27T20:59:20.821289Z","steps":["trace[50112092] 'process raft request' (duration: 156.01068ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:59:20.826308Z","caller":"traceutil/trace.go:171","msg":"trace[143254503] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"156.638815ms","start":"2026-04-27T20:59:20.669614Z","end":"2026-04-27T20:59:20.826253Z","steps":["trace[143254503] 'process raft request' (duration: 156.387808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:59:21.244222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.396592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:59:21.244443Z","caller":"traceutil/trace.go:171","msg":"trace[1593796601] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"402.823848ms","start":"2026-04-27T20:59:20.841596Z","end":"2026-04-27T20:59:21.244419Z","steps":["trace[1593796601] 'process raft request' (duration: 176.412537ms)","trace[1593796601] 'compare' (duration: 225.018492ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:59:21.244559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:59:20.841577Z","time spent":"402.942742ms","remote":"127.0.0.1:39560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:59:46.766146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.840068ms","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-27T20:59:46.766237Z","caller":"traceutil/trace.go:171","msg":"trace[2010041554] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:770; }","duration":"158.947541ms","start":"2026-04-27T20:59:46.60727Z","end":"2026-04-27T20:59:46.766218Z","steps":["trace[2010041554] 'range keys from in-memory index tree' (duration: 158.579991ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:00:15.745555Z","caller":"traceutil/trace.go:171","msg":"trace[2032038440] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"107.391024ms","start":"2026-04-27T21:00:15.638121Z","end":"2026-04-27T21:00:15.745512Z","steps":["trace[2032038440] 'process raft request' (duration: 107.069846ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:01.570271Z","caller":"traceutil/trace.go:171","msg":"trace[526532692] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1908; }","duration":"340.278153ms","start":"2026-04-27T21:01:01.229952Z","end":"2026-04-27T21:01:01.57023Z","steps":["trace[526532692] 'read index received' (duration: 56.491028ms)","trace[526532692] 'applied index is now lower than readState.Index' (duration: 283.785895ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:01:01.570364Z","caller":"traceutil/trace.go:171","msg":"trace[1464373002] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"345.598641ms","start":"2026-04-27T21:01:01.224721Z","end":"2026-04-27T21:01:01.570319Z","steps":["trace[1464373002] 'process raft request' (duration: 338.906545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:01.570657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.687584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T21:01:01.570753Z","caller":"traceutil/trace.go:171","msg":"trace[1999365271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1841; }","duration":"340.816068ms","start":"2026-04-27T21:01:01.229914Z","end":"2026-04-27T21:01:01.57073Z","steps":["trace[1999365271] 'agreement among raft nodes before linearized reading' (duration: 340.52307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:01.570816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:01.229903Z","time spent":"340.90371ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T21:01:01.570804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:01.224696Z","time spent":"346.013712ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:01:01.588476Z","caller":"traceutil/trace.go:171","msg":"trace[105381698] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"301.083174ms","start":"2026-04-27T21:01:01.287365Z","end":"2026-04-27T21:01:01.588448Z","steps":["trace[105381698] 'process raft request' (duration: 300.868459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:01.588624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:01.287327Z","time spent":"301.206178ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:01:01.632654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.107379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-27T21:01:01.632761Z","caller":"traceutil/trace.go:171","msg":"trace[612801196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1843; }","duration":"402.239383ms","start":"2026-04-27T21:01:01.230501Z","end":"2026-04-27T21:01:01.63274Z","steps":["trace[612801196] 'agreement among raft nodes before linearized reading' (duration: 402.024237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:01.632794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:01.230493Z","time spent":"402.293004ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T21:01:01.632893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.82968ms","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-27T21:01:01.632947Z","caller":"traceutil/trace.go:171","msg":"trace[1374786865] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1843; }","duration":"308.930252ms","start":"2026-04-27T21:01:01.324Z","end":"2026-04-27T21:01:01.63293Z","steps":["trace[1374786865] 'agreement among raft nodes before linearized reading' (duration: 308.705896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:01.633006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:01.323989Z","time spent":"309.006485ms","remote":"127.0.0.1:46910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-27T21:01:01.633137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.446335ms","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-27T21:01:01.633166Z","caller":"traceutil/trace.go:171","msg":"trace[651963077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1843; }","duration":"319.522916ms","start":"2026-04-27T21:01:01.313636Z","end":"2026-04-27T21:01:01.633159Z","steps":["trace[651963077] 'agreement among raft nodes before linearized reading' (duration: 319.411623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:01.633187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:01.313604Z","time spent":"319.578398ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T21:01:01.633457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.818558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0bf3c608-2b5c-47c2-858b-233ae750088b\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-27T21:01:01.633597Z","caller":"traceutil/trace.go:171","msg":"trace[284740553] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0bf3c608-2b5c-47c2-858b-233ae750088b; range_end:; response_count:1; response_revision:1843; }","duration":"384.996523ms","start":"2026-04-27T21:01:01.248569Z","end":"2026-04-27T21:01:01.633566Z","steps":["trace[284740553] 'agreement among raft nodes before linearized reading' (duration: 384.637584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:01.633678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:01.248554Z","time spent":"385.106456ms","remote":"127.0.0.1:39560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0bf3c608-2b5c-47c2-858b-233ae750088b\" "} {"level":"info","ts":"2026-04-27T21:01:01.968259Z","caller":"traceutil/trace.go:171","msg":"trace[676009799] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"120.695509ms","start":"2026-04-27T21:01:01.847531Z","end":"2026-04-27T21:01:01.968227Z","steps":["trace[676009799] 'process raft request' (duration: 120.475213ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:18.260437Z","caller":"traceutil/trace.go:171","msg":"trace[85756956] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"236.236105ms","start":"2026-04-27T21:01:18.024166Z","end":"2026-04-27T21:01:18.260402Z","steps":["trace[85756956] 'process raft request' (duration: 233.966443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:21.138844Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020034001992417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T21:01:21.639633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020034001992417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T21:01:22.130973Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.721995364s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T21:01:22.131976Z","caller":"traceutil/trace.go:171","msg":"trace[1094082565] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"1.723058593s","start":"2026-04-27T21:01:20.408875Z","end":"2026-04-27T21:01:22.131934Z","steps":["trace[1094082565] 'process raft request' (duration: 1.722809476s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.132204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:20.408856Z","time spent":"1.7232409s","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:01:22.137931Z","caller":"traceutil/trace.go:171","msg":"trace[1793112118] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"1.358109446s","start":"2026-04-27T21:01:20.779782Z","end":"2026-04-27T21:01:22.137892Z","steps":["trace[1793112118] 'process raft request' (duration: 1.35786477s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.1381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:20.779755Z","time spent":"1.358260421s","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:01:22.138121Z","caller":"traceutil/trace.go:171","msg":"trace[1760412806] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"1.286727156s","start":"2026-04-27T21:01:20.85137Z","end":"2026-04-27T21:01:22.138098Z","steps":["trace[1760412806] 'process raft request' (duration: 1.286443778s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.138266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:20.851347Z","time spent":"1.28684064s","remote":"127.0.0.1:39558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7930,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:01:22.141625Z","caller":"traceutil/trace.go:171","msg":"trace[1556378442] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"480.73557ms","start":"2026-04-27T21:01:21.660867Z","end":"2026-04-27T21:01:22.141603Z","steps":["trace[1556378442] 'process raft request' (duration: 480.287698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.141747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:21.660832Z","time spent":"480.855803ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:01:22.141779Z","caller":"traceutil/trace.go:171","msg":"trace[649605510] linearizableReadLoop","detail":"{readStateIndex:2054; appliedIndex:2050; }","duration":"1.503302246s","start":"2026-04-27T21:01:20.638441Z","end":"2026-04-27T21:01:22.141744Z","steps":["trace[649605510] 'read index received' (duration: 1.493301308s)","trace[649605510] 'applied index is now lower than readState.Index' (duration: 9.997438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:01:22.141946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.503475491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T21:01:22.142025Z","caller":"traceutil/trace.go:171","msg":"trace[1484055369] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1980; }","duration":"1.503573383s","start":"2026-04-27T21:01:20.638435Z","end":"2026-04-27T21:01:22.142008Z","steps":["trace[1484055369] 'agreement among raft nodes before linearized reading' (duration: 1.503404329s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.142059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:20.638364Z","time spent":"1.503687596s","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":11,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:01:22.142098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463304245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:01:22.142172Z","caller":"traceutil/trace.go:171","msg":"trace[1211759747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1980; }","duration":"1.463428228s","start":"2026-04-27T21:01:20.678729Z","end":"2026-04-27T21:01:22.142158Z","steps":["trace[1211759747] 'agreement among raft nodes before linearized reading' (duration: 1.463286714s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.142197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.35612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-27T21:01:22.142241Z","caller":"traceutil/trace.go:171","msg":"trace[1989610948] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1980; }","duration":"493.440832ms","start":"2026-04-27T21:01:21.648786Z","end":"2026-04-27T21:01:22.142227Z","steps":["trace[1989610948] 'agreement among raft nodes before linearized reading' (duration: 493.279998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.142208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.364843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T21:01:22.142279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:21.648765Z","time spent":"493.506714ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-27T21:01:22.14232Z","caller":"traceutil/trace.go:171","msg":"trace[1344228826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1980; }","duration":"412.500476ms","start":"2026-04-27T21:01:21.72978Z","end":"2026-04-27T21:01:22.14228Z","steps":["trace[1344228826] 'agreement among raft nodes before linearized reading' (duration: 412.256529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.142388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.169839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:01:22.142409Z","caller":"traceutil/trace.go:171","msg":"trace[1252591748] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1980; }","duration":"433.225751ms","start":"2026-04-27T21:01:21.709176Z","end":"2026-04-27T21:01:22.142402Z","steps":["trace[1252591748] 'agreement among raft nodes before linearized reading' (duration: 433.18838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.142429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:21.709158Z","time spent":"433.266032ms","remote":"127.0.0.1:33786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:01:22.142427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.595766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-27T21:01:22.142433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:21.729759Z","time spent":"412.662421ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T21:01:22.142435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.464283ms","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-27T21:01:22.142465Z","caller":"traceutil/trace.go:171","msg":"trace[1301683724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1980; }","duration":"760.679857ms","start":"2026-04-27T21:01:21.381774Z","end":"2026-04-27T21:01:22.142454Z","steps":["trace[1301683724] 'agreement among raft nodes before linearized reading' (duration: 760.491222ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:22.14248Z","caller":"traceutil/trace.go:171","msg":"trace[1225069184] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1980; }","duration":"471.561735ms","start":"2026-04-27T21:01:21.670905Z","end":"2026-04-27T21:01:22.142467Z","steps":["trace[1225069184] 'agreement among raft nodes before linearized reading' (duration: 471.473442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.142503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:21.670885Z","time spent":"471.612777ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T21:01:22.142243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:20.678693Z","time spent":"1.463535311s","remote":"127.0.0.1:39390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T21:01:22.142498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:21.381751Z","time spent":"760.737219ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T21:01:22.142601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.716555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T21:01:22.142589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.229253ms","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-27T21:01:22.14268Z","caller":"traceutil/trace.go:171","msg":"trace[1122581858] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1980; }","duration":"344.750856ms","start":"2026-04-27T21:01:21.797873Z","end":"2026-04-27T21:01:22.142624Z","steps":["trace[1122581858] 'agreement among raft nodes before linearized reading' (duration: 344.698945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.142684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.28967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-27T21:01:22.142729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:21.797795Z","time spent":"344.90834ms","remote":"127.0.0.1:33898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:01:22.142883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.973872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T21:01:22.14292Z","caller":"traceutil/trace.go:171","msg":"trace[931874233] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:1980; }","duration":"243.051474ms","start":"2026-04-27T21:01:21.899862Z","end":"2026-04-27T21:01:22.142913Z","steps":["trace[931874233] 'agreement among raft nodes before linearized reading' (duration: 242.986962ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:22.142702Z","caller":"traceutil/trace.go:171","msg":"trace[1944351926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1980; }","duration":"344.437148ms","start":"2026-04-27T21:01:21.798238Z","end":"2026-04-27T21:01:22.142675Z","steps":["trace[1944351926] 'agreement among raft nodes before linearized reading' (duration: 344.000736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.143036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:21.798213Z","time spent":"344.807348ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T21:01:22.142618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.352845981s","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-27T21:01:22.144042Z","caller":"traceutil/trace.go:171","msg":"trace[307761915] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1980; }","duration":"1.35423577s","start":"2026-04-27T21:01:20.789734Z","end":"2026-04-27T21:01:22.14397Z","steps":["trace[307761915] 'agreement among raft nodes before linearized reading' (duration: 1.352782199s)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:01:22.144089Z","caller":"traceutil/trace.go:171","msg":"trace[95365348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1980; }","duration":"399.31742ms","start":"2026-04-27T21:01:21.743387Z","end":"2026-04-27T21:01:22.142705Z","steps":["trace[95365348] 'agreement among raft nodes before linearized reading' (duration: 399.252259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:01:22.144128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:20.789719Z","time spent":"1.354391754s","remote":"127.0.0.1:46910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-27T21:01:22.144164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:01:21.743295Z","time spent":"400.851373ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T21:02:12.528187Z","caller":"traceutil/trace.go:171","msg":"trace[664738890] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"192.55328ms","start":"2026-04-27T21:02:12.33561Z","end":"2026-04-27T21:02:12.528163Z","steps":["trace[664738890] 'process raft request' (duration: 192.387836ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:12.53672Z","caller":"traceutil/trace.go:171","msg":"trace[1394320468] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"180.818066ms","start":"2026-04-27T21:02:12.355868Z","end":"2026-04-27T21:02:12.536686Z","steps":["trace[1394320468] 'process raft request' (duration: 180.663112ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:12.536896Z","caller":"traceutil/trace.go:171","msg":"trace[389800673] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"180.818216ms","start":"2026-04-27T21:02:12.356061Z","end":"2026-04-27T21:02:12.536879Z","steps":["trace[389800673] 'process raft request' (duration: 180.59176ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:12.536996Z","caller":"traceutil/trace.go:171","msg":"trace[1821544769] linearizableReadLoop","detail":"{readStateIndex:2355; appliedIndex:2352; }","duration":"122.677583ms","start":"2026-04-27T21:02:12.414293Z","end":"2026-04-27T21:02:12.536971Z","steps":["trace[1821544769] 'read index received' (duration: 113.784838ms)","trace[1821544769] 'applied index is now lower than readState.Index' (duration: 8.891655ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:02:12.537207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.91955ms","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-27T21:02:12.537243Z","caller":"traceutil/trace.go:171","msg":"trace[81376320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2270; }","duration":"123.018292ms","start":"2026-04-27T21:02:12.414216Z","end":"2026-04-27T21:02:12.537234Z","steps":["trace[81376320] 'agreement among raft nodes before linearized reading' (duration: 122.899869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:12.537333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.477934ms","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":"warn","ts":"2026-04-27T21:02:12.537358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.446532ms","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-27T21:02:12.537375Z","caller":"traceutil/trace.go:171","msg":"trace[1149398008] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2270; }","duration":"120.582746ms","start":"2026-04-27T21:02:12.416779Z","end":"2026-04-27T21:02:12.537362Z","steps":["trace[1149398008] 'agreement among raft nodes before linearized reading' (duration: 120.38418ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:12.537381Z","caller":"traceutil/trace.go:171","msg":"trace[293128437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2270; }","duration":"120.495053ms","start":"2026-04-27T21:02:12.416878Z","end":"2026-04-27T21:02:12.537373Z","steps":["trace[293128437] 'agreement among raft nodes before linearized reading' (duration: 120.435481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:02:12.537445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.267907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-27T21:02:12.537515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.306988ms","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-27T21:02:12.537545Z","caller":"traceutil/trace.go:171","msg":"trace[166699901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2270; }","duration":"120.393481ms","start":"2026-04-27T21:02:12.417126Z","end":"2026-04-27T21:02:12.53752Z","steps":["trace[166699901] 'agreement among raft nodes before linearized reading' (duration: 120.118182ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:02:12.537611Z","caller":"traceutil/trace.go:171","msg":"trace[230892406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2270; }","duration":"120.443502ms","start":"2026-04-27T21:02:12.417146Z","end":"2026-04-27T21:02:12.537589Z","steps":["trace[230892406] 'agreement among raft nodes before linearized reading' (duration: 120.201735ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:55.018791Z","caller":"traceutil/trace.go:171","msg":"trace[552829391] transaction","detail":"{read_only:false; response_revision:3009; number_of_response:1; }","duration":"113.241101ms","start":"2026-04-27T21:03:54.905523Z","end":"2026-04-27T21:03:55.018764Z","steps":["trace[552829391] 'process raft request' (duration: 113.047465ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:55.140311Z","caller":"traceutil/trace.go:171","msg":"trace[1466976684] transaction","detail":"{read_only:false; response_revision:3010; number_of_response:1; }","duration":"217.202441ms","start":"2026-04-27T21:03:54.92308Z","end":"2026-04-27T21:03:55.140282Z","steps":["trace[1466976684] 'process raft request' (duration: 216.940284ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:55.140352Z","caller":"traceutil/trace.go:171","msg":"trace[701612845] transaction","detail":"{read_only:false; response_revision:3011; number_of_response:1; }","duration":"214.874446ms","start":"2026-04-27T21:03:54.925434Z","end":"2026-04-27T21:03:55.140308Z","steps":["trace[701612845] 'process raft request' (duration: 214.752192ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:03:55.140573Z","caller":"traceutil/trace.go:171","msg":"trace[373013957] transaction","detail":"{read_only:false; response_revision:3012; number_of_response:1; }","duration":"163.321398ms","start":"2026-04-27T21:03:54.977217Z","end":"2026-04-27T21:03:55.140539Z","steps":["trace[373013957] 'process raft request' (duration: 163.014839ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:06:31.780891Z","caller":"traceutil/trace.go:171","msg":"trace[910136016] linearizableReadLoop","detail":"{readStateIndex:4184; appliedIndex:4183; }","duration":"100.156053ms","start":"2026-04-27T21:06:31.680712Z","end":"2026-04-27T21:06:31.780868Z","steps":["trace[910136016] 'read index received' (duration: 99.991468ms)","trace[910136016] 'applied index is now lower than readState.Index' (duration: 163.045µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:06:31.781095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.364728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:06:31.781173Z","caller":"traceutil/trace.go:171","msg":"trace[288251311] transaction","detail":"{read_only:false; response_revision:4031; number_of_response:1; }","duration":"161.668602ms","start":"2026-04-27T21:06:31.619471Z","end":"2026-04-27T21:06:31.781139Z","steps":["trace[288251311] 'process raft request' (duration: 161.23161ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:06:31.781248Z","caller":"traceutil/trace.go:171","msg":"trace[1567110745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4031; }","duration":"100.628126ms","start":"2026-04-27T21:06:31.680607Z","end":"2026-04-27T21:06:31.781236Z","steps":["trace[1567110745] 'agreement among raft nodes before linearized reading' (duration: 100.366349ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:07:59.74234Z","caller":"traceutil/trace.go:171","msg":"trace[1624552974] linearizableReadLoop","detail":"{readStateIndex:5418; appliedIndex:5417; }","duration":"102.584618ms","start":"2026-04-27T21:07:59.639729Z","end":"2026-04-27T21:07:59.742314Z","steps":["trace[1624552974] 'read index received' (duration: 102.342031ms)","trace[1624552974] 'applied index is now lower than readState.Index' (duration: 241.146µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:07:59.742823Z","caller":"traceutil/trace.go:171","msg":"trace[2012865637] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"212.896299ms","start":"2026-04-27T21:07:59.529911Z","end":"2026-04-27T21:07:59.742807Z","steps":["trace[2012865637] 'process raft request' (duration: 212.232931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:07:59.743177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.42874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-jn4zj-tls\" ","response":"range_response_count:1 size:2183"} {"level":"info","ts":"2026-04-27T21:07:59.743226Z","caller":"traceutil/trace.go:171","msg":"trace[678347204] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-jn4zj-tls; range_end:; response_count:1; response_revision:5222; }","duration":"103.588815ms","start":"2026-04-27T21:07:59.639624Z","end":"2026-04-27T21:07:59.743213Z","steps":["trace[678347204] 'agreement among raft nodes before linearized reading' (duration: 103.40336ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:00.283822Z","caller":"traceutil/trace.go:171","msg":"trace[1831844497] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"102.116383ms","start":"2026-04-27T21:08:00.181677Z","end":"2026-04-27T21:08:00.283794Z","steps":["trace[1831844497] 'process raft request' (duration: 101.939469ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:00.28384Z","caller":"traceutil/trace.go:171","msg":"trace[522641885] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"116.318198ms","start":"2026-04-27T21:08:00.167479Z","end":"2026-04-27T21:08:00.283797Z","steps":["trace[522641885] 'process raft request' (duration: 115.956109ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:11.417559Z","caller":"traceutil/trace.go:171","msg":"trace[225765434] linearizableReadLoop","detail":"{readStateIndex:5670; appliedIndex:5669; }","duration":"119.157199ms","start":"2026-04-27T21:08:11.298375Z","end":"2026-04-27T21:08:11.417532Z","steps":["trace[225765434] 'read index received' (duration: 118.830239ms)","trace[225765434] 'applied index is now lower than readState.Index' (duration: 325.85µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:08:11.417627Z","caller":"traceutil/trace.go:171","msg":"trace[1458946305] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"127.016678ms","start":"2026-04-27T21:08:11.290566Z","end":"2026-04-27T21:08:11.417583Z","steps":["trace[1458946305] 'process raft request' (duration: 126.759359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:08:11.41773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.338034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:08:11.417772Z","caller":"traceutil/trace.go:171","msg":"trace[691110114] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:5467; }","duration":"119.451237ms","start":"2026-04-27T21:08:11.298308Z","end":"2026-04-27T21:08:11.417759Z","steps":["trace[691110114] 'agreement among raft nodes before linearized reading' (duration: 119.341854ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:12.183287Z","caller":"traceutil/trace.go:171","msg":"trace[1091225838] transaction","detail":"{read_only:false; response_revision:5477; number_of_response:1; }","duration":"124.706364ms","start":"2026-04-27T21:08:12.058545Z","end":"2026-04-27T21:08:12.183251Z","steps":["trace[1091225838] 'process raft request' (duration: 124.508938ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:12.348225Z","caller":"traceutil/trace.go:171","msg":"trace[1392294738] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"141.83492ms","start":"2026-04-27T21:08:12.206351Z","end":"2026-04-27T21:08:12.348186Z","steps":["trace[1392294738] 'process raft request' (duration: 141.628954ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:12.370864Z","caller":"traceutil/trace.go:171","msg":"trace[315847725] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"122.707138ms","start":"2026-04-27T21:08:12.248133Z","end":"2026-04-27T21:08:12.37084Z","steps":["trace[315847725] 'process raft request' (duration: 116.601218ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:12.371148Z","caller":"traceutil/trace.go:171","msg":"trace[1663098973] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"119.88899ms","start":"2026-04-27T21:08:12.251237Z","end":"2026-04-27T21:08:12.371126Z","steps":["trace[1663098973] 'process raft request' (duration: 119.55635ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:20.015844Z","caller":"traceutil/trace.go:171","msg":"trace[1856653061] transaction","detail":"{read_only:false; response_revision:5646; number_of_response:1; }","duration":"174.608803ms","start":"2026-04-27T21:08:19.841189Z","end":"2026-04-27T21:08:20.015798Z","steps":["trace[1856653061] 'process raft request' (duration: 109.147349ms)","trace[1856653061] 'compare' (duration: 65.087003ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:08:20.017714Z","caller":"traceutil/trace.go:171","msg":"trace[879941313] transaction","detail":"{read_only:false; response_revision:5649; number_of_response:1; }","duration":"176.057993ms","start":"2026-04-27T21:08:19.841625Z","end":"2026-04-27T21:08:20.017683Z","steps":["trace[879941313] 'process raft request' (duration: 175.867357ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:20.017708Z","caller":"traceutil/trace.go:171","msg":"trace[604530104] transaction","detail":"{read_only:false; response_revision:5648; number_of_response:1; }","duration":"176.254619ms","start":"2026-04-27T21:08:19.841404Z","end":"2026-04-27T21:08:20.017659Z","steps":["trace[604530104] 'process raft request' (duration: 176.000591ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:20.017865Z","caller":"traceutil/trace.go:171","msg":"trace[1414905468] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"174.313765ms","start":"2026-04-27T21:08:19.84343Z","end":"2026-04-27T21:08:20.017744Z","steps":["trace[1414905468] 'process raft request' (duration: 174.118789ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:20.017993Z","caller":"traceutil/trace.go:171","msg":"trace[1064315569] transaction","detail":"{read_only:false; response_revision:5647; number_of_response:1; }","duration":"176.587858ms","start":"2026-04-27T21:08:19.841357Z","end":"2026-04-27T21:08:20.017945Z","steps":["trace[1064315569] 'process raft request' (duration: 175.861097ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:20.018057Z","caller":"traceutil/trace.go:171","msg":"trace[870233654] transaction","detail":"{read_only:false; response_revision:5651; number_of_response:1; }","duration":"171.547158ms","start":"2026-04-27T21:08:19.846495Z","end":"2026-04-27T21:08:20.018042Z","steps":["trace[870233654] 'process raft request' (duration: 171.145056ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:20.018212Z","caller":"traceutil/trace.go:171","msg":"trace[2100385861] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"171.144146ms","start":"2026-04-27T21:08:19.847028Z","end":"2026-04-27T21:08:20.018172Z","steps":["trace[2100385861] 'process raft request' (duration: 170.9446ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:08:42.801045Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2940} {"level":"info","ts":"2026-04-27T21:08:42.868935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2940,"took":"66.179684ms","hash":3257417261,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":31666176,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-27T21:08:42.868998Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3257417261,"revision":2940,"compact-revision":-1} {"level":"info","ts":"2026-04-27T21:13:42.807419Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5872} {"level":"info","ts":"2026-04-27T21:13:42.898588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5872,"took":"88.888003ms","hash":4078668914,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":25968640,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-27T21:13:42.898674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4078668914,"revision":5872,"compact-revision":2940} {"level":"warn","ts":"2026-04-27T21:14:34.346776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.422854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:14:34.346935Z","caller":"traceutil/trace.go:171","msg":"trace[2029186801] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:8508; }","duration":"193.62232ms","start":"2026-04-27T21:14:34.153281Z","end":"2026-04-27T21:14:34.346903Z","steps":["trace[2029186801] 'count revisions from in-memory index tree' (duration: 193.253889ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:14:46.962009Z","caller":"traceutil/trace.go:171","msg":"trace[778100217] linearizableReadLoop","detail":"{readStateIndex:8879; appliedIndex:8877; }","duration":"165.218635ms","start":"2026-04-27T21:14:46.796758Z","end":"2026-04-27T21:14:46.961977Z","steps":["trace[778100217] 'read index received' (duration: 165.050421ms)","trace[778100217] 'applied index is now lower than readState.Index' (duration: 166.744µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:14:46.962053Z","caller":"traceutil/trace.go:171","msg":"trace[998713738] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"214.618635ms","start":"2026-04-27T21:14:46.747397Z","end":"2026-04-27T21:14:46.962016Z","steps":["trace[998713738] 'process raft request' (duration: 214.342288ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:14:46.962182Z","caller":"traceutil/trace.go:171","msg":"trace[607147062] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"214.71555ms","start":"2026-04-27T21:14:46.747417Z","end":"2026-04-27T21:14:46.962133Z","steps":["trace[607147062] 'process raft request' (duration: 214.514223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:14:46.962259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.474304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-27T21:14:46.962374Z","caller":"traceutil/trace.go:171","msg":"trace[1974576282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8576; }","duration":"165.636168ms","start":"2026-04-27T21:14:46.796724Z","end":"2026-04-27T21:14:46.962361Z","steps":["trace[1974576282] 'agreement among raft nodes before linearized reading' (duration: 165.386821ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:14:46.971712Z","caller":"traceutil/trace.go:171","msg":"trace[1037232227] transaction","detail":"{read_only:false; response_revision:8577; number_of_response:1; }","duration":"170.188135ms","start":"2026-04-27T21:14:46.801507Z","end":"2026-04-27T21:14:46.971695Z","steps":["trace[1037232227] 'process raft request' (duration: 170.099843ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:06.232961Z","caller":"traceutil/trace.go:171","msg":"trace[1189940338] linearizableReadLoop","detail":"{readStateIndex:8986; appliedIndex:8985; }","duration":"179.532617ms","start":"2026-04-27T21:15:06.053396Z","end":"2026-04-27T21:15:06.232928Z","steps":["trace[1189940338] 'read index received' (duration: 179.282589ms)","trace[1189940338] 'applied index is now lower than readState.Index' (duration: 248.268µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:15:06.233004Z","caller":"traceutil/trace.go:171","msg":"trace[1490226413] transaction","detail":"{read_only:false; response_revision:8680; number_of_response:1; }","duration":"187.409166ms","start":"2026-04-27T21:15:06.045559Z","end":"2026-04-27T21:15:06.232969Z","steps":["trace[1490226413] 'process raft request' (duration: 187.134407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:06.233355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.927488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-27T21:15:06.233427Z","caller":"traceutil/trace.go:171","msg":"trace[239580305] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:8680; }","duration":"180.060092ms","start":"2026-04-27T21:15:06.053345Z","end":"2026-04-27T21:15:06.233405Z","steps":["trace[239580305] 'agreement among raft nodes before linearized reading' (duration: 179.768374ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:06.281203Z","caller":"traceutil/trace.go:171","msg":"trace[579294224] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"116.385473ms","start":"2026-04-27T21:15:06.164772Z","end":"2026-04-27T21:15:06.281157Z","steps":["trace[579294224] 'process raft request' (duration: 115.907669ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:06.281256Z","caller":"traceutil/trace.go:171","msg":"trace[566054186] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"179.221757ms","start":"2026-04-27T21:15:06.101989Z","end":"2026-04-27T21:15:06.281211Z","steps":["trace[566054186] 'process raft request' (duration: 150.329059ms)","trace[566054186] 'compare' (duration: 28.043014ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:15:06.28133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.062522ms","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-27T21:15:06.281412Z","caller":"traceutil/trace.go:171","msg":"trace[732935901] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8682; }","duration":"126.222186ms","start":"2026-04-27T21:15:06.155167Z","end":"2026-04-27T21:15:06.281389Z","steps":["trace[732935901] 'agreement among raft nodes before linearized reading' (duration: 126.092102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:07.367929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.890509ms","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-27T21:15:07.368035Z","caller":"traceutil/trace.go:171","msg":"trace[1974468924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8686; }","duration":"159.075044ms","start":"2026-04-27T21:15:07.208935Z","end":"2026-04-27T21:15:07.36801Z","steps":["trace[1974468924] 'range keys from in-memory index tree' (duration: 158.499778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:07.368358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.870735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T21:15:07.36847Z","caller":"traceutil/trace.go:171","msg":"trace[1573116949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8686; }","duration":"180.08752ms","start":"2026-04-27T21:15:07.188358Z","end":"2026-04-27T21:15:07.368446Z","steps":["trace[1573116949] 'range keys from in-memory index tree' (duration: 179.544906ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:07.532975Z","caller":"traceutil/trace.go:171","msg":"trace[1418941829] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"159.775963ms","start":"2026-04-27T21:15:07.37316Z","end":"2026-04-27T21:15:07.532936Z","steps":["trace[1418941829] 'process raft request' (duration: 98.664976ms)","trace[1418941829] 'compare' (duration: 60.951913ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:15:14.482265Z","caller":"traceutil/trace.go:171","msg":"trace[1802555803] transaction","detail":"{read_only:false; response_revision:8728; number_of_response:1; }","duration":"102.557185ms","start":"2026-04-27T21:15:14.379673Z","end":"2026-04-27T21:15:14.48223Z","steps":["trace[1802555803] 'process raft request' (duration: 102.280437ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:14.4915Z","caller":"traceutil/trace.go:171","msg":"trace[946488009] transaction","detail":"{read_only:false; response_revision:8729; number_of_response:1; }","duration":"101.23737ms","start":"2026-04-27T21:15:14.39023Z","end":"2026-04-27T21:15:14.491467Z","steps":["trace[946488009] 'process raft request' (duration: 100.946121ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:14.49149Z","caller":"traceutil/trace.go:171","msg":"trace[1197522944] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"101.029843ms","start":"2026-04-27T21:15:14.390422Z","end":"2026-04-27T21:15:14.491452Z","steps":["trace[1197522944] 'process raft request' (duration: 100.925211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:22.93215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020034002009499,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T21:15:23.077602Z","caller":"traceutil/trace.go:171","msg":"trace[1014597858] transaction","detail":"{read_only:false; response_revision:8815; number_of_response:1; }","duration":"681.676075ms","start":"2026-04-27T21:15:22.395893Z","end":"2026-04-27T21:15:23.077569Z","steps":["trace[1014597858] 'process raft request' (duration: 681.50534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:23.07779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:22.395873Z","time spent":"681.821119ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:15:23.480419Z","caller":"traceutil/trace.go:171","msg":"trace[530685690] transaction","detail":"{read_only:false; response_revision:8817; number_of_response:1; }","duration":"884.636455ms","start":"2026-04-27T21:15:22.595753Z","end":"2026-04-27T21:15:23.480389Z","steps":["trace[530685690] 'process raft request' (duration: 884.549532ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:23.480476Z","caller":"traceutil/trace.go:171","msg":"trace[262768099] transaction","detail":"{read_only:false; response_revision:8816; number_of_response:1; }","duration":"921.745642ms","start":"2026-04-27T21:15:22.558674Z","end":"2026-04-27T21:15:23.48042Z","steps":["trace[262768099] 'process raft request' (duration: 921.433593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:23.480663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:22.59572Z","time spent":"884.78995ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:15:23.480765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049228343s","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-27T21:15:23.48081Z","caller":"traceutil/trace.go:171","msg":"trace[821538104] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8818; }","duration":"1.049331056s","start":"2026-04-27T21:15:22.43147Z","end":"2026-04-27T21:15:23.480801Z","steps":["trace[821538104] 'agreement among raft nodes before linearized reading' (duration: 1.049229973s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:23.480819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:22.558645Z","time spent":"921.948267ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:15:23.480843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:22.431447Z","time spent":"1.049389197s","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-27T21:15:23.481032Z","caller":"traceutil/trace.go:171","msg":"trace[1239467670] transaction","detail":"{read_only:false; response_revision:8818; number_of_response:1; }","duration":"881.347793ms","start":"2026-04-27T21:15:22.599674Z","end":"2026-04-27T21:15:23.481022Z","steps":["trace[1239467670] 'process raft request' (duration: 880.675544ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:23.480662Z","caller":"traceutil/trace.go:171","msg":"trace[1317344836] linearizableReadLoop","detail":"{readStateIndex:9130; appliedIndex:9126; }","duration":"1.04909808s","start":"2026-04-27T21:15:22.431543Z","end":"2026-04-27T21:15:23.480641Z","steps":["trace[1317344836] 'read index received' (duration: 645.976847ms)","trace[1317344836] 'applied index is now lower than readState.Index' (duration: 403.119553ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:15:23.481131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:22.599645Z","time spent":"881.423456ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:15:23.48133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.645935ms","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-27T21:15:23.481335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.753752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-27T21:15:23.481365Z","caller":"traceutil/trace.go:171","msg":"trace[2128468216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8818; }","duration":"254.724476ms","start":"2026-04-27T21:15:23.226632Z","end":"2026-04-27T21:15:23.481356Z","steps":["trace[2128468216] 'agreement among raft nodes before linearized reading' (duration: 254.596343ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:23.481371Z","caller":"traceutil/trace.go:171","msg":"trace[46230787] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:8818; }","duration":"834.828194ms","start":"2026-04-27T21:15:22.646532Z","end":"2026-04-27T21:15:23.481361Z","steps":["trace[46230787] 'agreement among raft nodes before linearized reading' (duration: 834.69458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:23.481401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:22.646511Z","time spent":"834.883656ms","remote":"127.0.0.1:39536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1803,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-04-27T21:15:23.481568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.053136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:15:23.481601Z","caller":"traceutil/trace.go:171","msg":"trace[1994550264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8818; }","duration":"803.139109ms","start":"2026-04-27T21:15:22.678453Z","end":"2026-04-27T21:15:23.481592Z","steps":["trace[1994550264] 'agreement among raft nodes before linearized reading' (duration: 803.088908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:23.481626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:22.678415Z","time spent":"803.206681ms","remote":"127.0.0.1:39374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T21:15:23.481683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.119027ms","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-27T21:15:23.481724Z","caller":"traceutil/trace.go:171","msg":"trace[723166824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8818; }","duration":"226.2155ms","start":"2026-04-27T21:15:23.255498Z","end":"2026-04-27T21:15:23.481714Z","steps":["trace[723166824] 'agreement among raft nodes before linearized reading' (duration: 226.105407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:23.481765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.188442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T21:15:23.481703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.930614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T21:15:23.481809Z","caller":"traceutil/trace.go:171","msg":"trace[562331658] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8818; }","duration":"244.278375ms","start":"2026-04-27T21:15:23.237519Z","end":"2026-04-27T21:15:23.481797Z","steps":["trace[562331658] 'agreement among raft nodes before linearized reading' (duration: 244.198402ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:23.481859Z","caller":"traceutil/trace.go:171","msg":"trace[358515867] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8818; }","duration":"657.133049ms","start":"2026-04-27T21:15:22.824691Z","end":"2026-04-27T21:15:23.481824Z","steps":["trace[358515867] 'agreement among raft nodes before linearized reading' (duration: 656.843041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:23.481885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.735565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-27T21:15:23.481966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:22.824682Z","time spent":"657.265063ms","remote":"127.0.0.1:46926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-04-27T21:15:23.481982Z","caller":"traceutil/trace.go:171","msg":"trace[819957123] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8818; }","duration":"687.829107ms","start":"2026-04-27T21:15:22.794137Z","end":"2026-04-27T21:15:23.481966Z","steps":["trace[819957123] 'agreement among raft nodes before linearized reading' (duration: 687.308883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:23.482231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:15:22.794091Z","time spent":"688.127525ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-27T21:15:36.84225Z","caller":"traceutil/trace.go:171","msg":"trace[1548940653] transaction","detail":"{read_only:false; response_revision:8932; number_of_response:1; }","duration":"103.827144ms","start":"2026-04-27T21:15:36.738389Z","end":"2026-04-27T21:15:36.842216Z","steps":["trace[1548940653] 'process raft request' (duration: 103.602037ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:37.922161Z","caller":"traceutil/trace.go:171","msg":"trace[496918613] linearizableReadLoop","detail":"{readStateIndex:9261; appliedIndex:9260; }","duration":"223.055507ms","start":"2026-04-27T21:15:37.699077Z","end":"2026-04-27T21:15:37.922133Z","steps":["trace[496918613] 'read index received' (duration: 189.295853ms)","trace[496918613] 'applied index is now lower than readState.Index' (duration: 33.758334ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:15:37.922247Z","caller":"traceutil/trace.go:171","msg":"trace[730020021] transaction","detail":"{read_only:false; response_revision:8943; number_of_response:1; }","duration":"228.320295ms","start":"2026-04-27T21:15:37.693879Z","end":"2026-04-27T21:15:37.9222Z","steps":["trace[730020021] 'process raft request' (duration: 194.479048ms)","trace[730020021] 'compare' (duration: 33.474686ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:15:37.922398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.312185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-04-27T21:15:37.922454Z","caller":"traceutil/trace.go:171","msg":"trace[1367238063] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:8943; }","duration":"223.404307ms","start":"2026-04-27T21:15:37.69903Z","end":"2026-04-27T21:15:37.922434Z","steps":["trace[1367238063] 'agreement among raft nodes before linearized reading' (duration: 223.233652ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:37.955874Z","caller":"traceutil/trace.go:171","msg":"trace[1801773996] transaction","detail":"{read_only:false; response_revision:8944; number_of_response:1; }","duration":"217.372558ms","start":"2026-04-27T21:15:37.738479Z","end":"2026-04-27T21:15:37.955852Z","steps":["trace[1801773996] 'process raft request' (duration: 217.278546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:37.991356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.249751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T21:15:37.991505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.727664ms","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-27T21:15:37.991594Z","caller":"traceutil/trace.go:171","msg":"trace[832304523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8945; }","duration":"224.886628ms","start":"2026-04-27T21:15:37.766685Z","end":"2026-04-27T21:15:37.991571Z","steps":["trace[832304523] 'agreement among raft nodes before linearized reading' (duration: 224.645912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:37.991556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.580858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-hb2dn\" ","response":"range_response_count:1 size:4649"} {"level":"info","ts":"2026-04-27T21:15:37.991518Z","caller":"traceutil/trace.go:171","msg":"trace[1381817575] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:8945; }","duration":"214.461896ms","start":"2026-04-27T21:15:37.777025Z","end":"2026-04-27T21:15:37.991487Z","steps":["trace[1381817575] 'agreement among raft nodes before linearized reading' (duration: 214.174358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:37.991715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.949916ms","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-27T21:15:37.991757Z","caller":"traceutil/trace.go:171","msg":"trace[1865807385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8945; }","duration":"212.012327ms","start":"2026-04-27T21:15:37.779735Z","end":"2026-04-27T21:15:37.991747Z","steps":["trace[1865807385] 'agreement among raft nodes before linearized reading' (duration: 211.828662ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:37.991687Z","caller":"traceutil/trace.go:171","msg":"trace[1527333183] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-hb2dn; range_end:; response_count:1; response_revision:8945; }","duration":"254.745493ms","start":"2026-04-27T21:15:37.736914Z","end":"2026-04-27T21:15:37.99166Z","steps":["trace[1527333183] 'agreement among raft nodes before linearized reading' (duration: 254.349292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:37.991528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.714287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6zlcg\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-27T21:15:37.991929Z","caller":"traceutil/trace.go:171","msg":"trace[1189702516] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6zlcg; range_end:; response_count:1; response_revision:8945; }","duration":"135.125828ms","start":"2026-04-27T21:15:37.856769Z","end":"2026-04-27T21:15:37.991895Z","steps":["trace[1189702516] 'agreement among raft nodes before linearized reading' (duration: 134.625125ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:38.165282Z","caller":"traceutil/trace.go:171","msg":"trace[684938012] transaction","detail":"{read_only:false; response_revision:8946; number_of_response:1; }","duration":"166.998519ms","start":"2026-04-27T21:15:37.998227Z","end":"2026-04-27T21:15:38.165225Z","steps":["trace[684938012] 'process raft request' (duration: 164.482889ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:38.189816Z","caller":"traceutil/trace.go:171","msg":"trace[1012906219] linearizableReadLoop","detail":"{readStateIndex:9265; appliedIndex:9263; }","duration":"191.399531ms","start":"2026-04-27T21:15:37.99839Z","end":"2026-04-27T21:15:38.18979Z","steps":["trace[1012906219] 'read index received' (duration: 164.420117ms)","trace[1012906219] 'applied index is now lower than readState.Index' (duration: 26.977964ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:15:38.189901Z","caller":"traceutil/trace.go:171","msg":"trace[253638703] transaction","detail":"{read_only:false; response_revision:8947; number_of_response:1; }","duration":"191.600597ms","start":"2026-04-27T21:15:37.998233Z","end":"2026-04-27T21:15:38.189834Z","steps":["trace[253638703] 'process raft request' (duration: 191.3627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:15:38.190446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.028989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228751"} {"level":"info","ts":"2026-04-27T21:15:38.189962Z","caller":"traceutil/trace.go:171","msg":"trace[1779076794] transaction","detail":"{read_only:false; response_revision:8948; number_of_response:1; }","duration":"182.268357ms","start":"2026-04-27T21:15:38.007675Z","end":"2026-04-27T21:15:38.189944Z","steps":["trace[1779076794] 'process raft request' (duration: 182.0455ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:38.190511Z","caller":"traceutil/trace.go:171","msg":"trace[1636910751] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8948; }","duration":"192.134692ms","start":"2026-04-27T21:15:37.998361Z","end":"2026-04-27T21:15:38.190495Z","steps":["trace[1636910751] 'agreement among raft nodes before linearized reading' (duration: 191.628138ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:15:55.262739Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024d7.wal"} {"level":"info","ts":"2026-04-27T21:16:49.855818Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T21:16:49.86267Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-27T21:16:49.862774Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-27T21:16:57.220115Z","caller":"traceutil/trace.go:171","msg":"trace[858604460] transaction","detail":"{read_only:false; response_revision:9705; number_of_response:1; }","duration":"186.121795ms","start":"2026-04-27T21:16:57.033964Z","end":"2026-04-27T21:16:57.220086Z","steps":["trace[858604460] 'process raft request' (duration: 185.93801ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:18:42.814215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8038} {"level":"info","ts":"2026-04-27T21:18:42.882311Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8038,"took":"65.257886ms","hash":3482890022,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":28332032,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-27T21:18:42.882381Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3482890022,"revision":8038,"compact-revision":5872} {"level":"info","ts":"2026-04-27T21:22:31.194663Z","caller":"traceutil/trace.go:171","msg":"trace[206837515] transaction","detail":"{read_only:false; response_revision:12106; number_of_response:1; }","duration":"221.971387ms","start":"2026-04-27T21:22:30.972654Z","end":"2026-04-27T21:22:31.194626Z","steps":["trace[206837515] 'process raft request' (duration: 199.736674ms)","trace[206837515] 'compare' (duration: 21.872173ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:23:42.81962Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10432} {"level":"info","ts":"2026-04-27T21:23:42.902208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10432,"took":"79.705123ms","hash":3808703348,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":25927680,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-27T21:23:42.902282Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3808703348,"revision":10432,"compact-revision":8038} {"level":"info","ts":"2026-04-27T21:28:42.826061Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12593} {"level":"info","ts":"2026-04-27T21:28:42.904928Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12593,"took":"75.342131ms","hash":3807436794,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":26796032,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-27T21:28:42.905014Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3807436794,"revision":12593,"compact-revision":10432} {"level":"warn","ts":"2026-04-27T21:28:52.507394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.572832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6zlcg\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-27T21:28:52.507503Z","caller":"traceutil/trace.go:171","msg":"trace[370932645] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6zlcg; range_end:; response_count:1; response_revision:14667; }","duration":"102.734026ms","start":"2026-04-27T21:28:52.404742Z","end":"2026-04-27T21:28:52.507476Z","steps":["trace[370932645] 'range keys from in-memory index tree' (duration: 102.333225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:28:52.507881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.229558ms","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-27T21:28:52.507929Z","caller":"traceutil/trace.go:171","msg":"trace[1491141773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14667; }","duration":"150.30664ms","start":"2026-04-27T21:28:52.357608Z","end":"2026-04-27T21:28:52.507915Z","steps":["trace[1491141773] 'range keys from in-memory index tree' (duration: 150.110355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:28:52.507916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.244632ms","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-27T21:28:52.507977Z","caller":"traceutil/trace.go:171","msg":"trace[2050559358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14667; }","duration":"148.325245ms","start":"2026-04-27T21:28:52.359639Z","end":"2026-04-27T21:28:52.507964Z","steps":["trace[2050559358] 'range keys from in-memory index tree' (duration: 148.13516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:29:08.19113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.410822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T21:29:08.191489Z","caller":"traceutil/trace.go:171","msg":"trace[1010057085] transaction","detail":"{read_only:false; response_revision:14764; number_of_response:1; }","duration":"266.70934ms","start":"2026-04-27T21:29:07.924751Z","end":"2026-04-27T21:29:08.19146Z","steps":["trace[1010057085] 'process raft request' (duration: 54.889687ms)","trace[1010057085] 'compare' (duration: 211.307409ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:29:08.355346Z","caller":"traceutil/trace.go:171","msg":"trace[1451972280] transaction","detail":"{read_only:false; response_revision:14765; number_of_response:1; }","duration":"159.808367ms","start":"2026-04-27T21:29:08.195513Z","end":"2026-04-27T21:29:08.355321Z","steps":["trace[1451972280] 'process raft request' (duration: 154.20445ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:29:28.954683Z","caller":"traceutil/trace.go:171","msg":"trace[2097703883] transaction","detail":"{read_only:false; response_revision:15068; number_of_response:1; }","duration":"139.9835ms","start":"2026-04-27T21:29:28.814669Z","end":"2026-04-27T21:29:28.954653Z","steps":["trace[2097703883] 'process raft request' (duration: 139.834996ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:29:28.960295Z","caller":"traceutil/trace.go:171","msg":"trace[1777408831] transaction","detail":"{read_only:false; response_revision:15069; number_of_response:1; }","duration":"144.989011ms","start":"2026-04-27T21:29:28.815286Z","end":"2026-04-27T21:29:28.960275Z","steps":["trace[1777408831] 'process raft request' (duration: 144.712653ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:29:28.960342Z","caller":"traceutil/trace.go:171","msg":"trace[702582652] transaction","detail":"{read_only:false; response_revision:15070; number_of_response:1; }","duration":"142.157652ms","start":"2026-04-27T21:29:28.818166Z","end":"2026-04-27T21:29:28.960323Z","steps":["trace[702582652] 'process raft request' (duration: 141.971627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:29:29.229398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.66289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9b981209-1544-4551-b5c1-0043317c581b\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-04-27T21:29:29.229478Z","caller":"traceutil/trace.go:171","msg":"trace[769447088] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9b981209-1544-4551-b5c1-0043317c581b; range_end:; response_count:1; response_revision:15070; }","duration":"145.794394ms","start":"2026-04-27T21:29:29.083663Z","end":"2026-04-27T21:29:29.229457Z","steps":["trace[769447088] 'range keys from in-memory index tree' (duration: 145.487085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:29:29.229593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.391701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:17 size:156824"} {"level":"info","ts":"2026-04-27T21:29:29.229699Z","caller":"traceutil/trace.go:171","msg":"trace[2036273739] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:17; response_revision:15070; }","duration":"141.509794ms","start":"2026-04-27T21:29:29.088162Z","end":"2026-04-27T21:29:29.229672Z","steps":["trace[2036273739] 'range keys from in-memory index tree' (duration: 141.053032ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:30:21.239414Z","caller":"traceutil/trace.go:171","msg":"trace[40948290] transaction","detail":"{read_only:false; response_revision:15697; number_of_response:1; }","duration":"118.926842ms","start":"2026-04-27T21:30:21.120442Z","end":"2026-04-27T21:30:21.239369Z","steps":["trace[40948290] 'process raft request' (duration: 118.658574ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:30:21.257996Z","caller":"traceutil/trace.go:171","msg":"trace[832664852] transaction","detail":"{read_only:false; response_revision:15698; number_of_response:1; }","duration":"137.341657ms","start":"2026-04-27T21:30:21.120625Z","end":"2026-04-27T21:30:21.257967Z","steps":["trace[832664852] 'process raft request' (duration: 137.112521ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:30:21.258161Z","caller":"traceutil/trace.go:171","msg":"trace[740715460] transaction","detail":"{read_only:false; response_revision:15699; number_of_response:1; }","duration":"135.743692ms","start":"2026-04-27T21:30:21.122387Z","end":"2026-04-27T21:30:21.258131Z","steps":["trace[740715460] 'process raft request' (duration: 135.521235ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:30:55.236827Z","caller":"traceutil/trace.go:171","msg":"trace[2013139891] transaction","detail":"{read_only:false; response_revision:15874; number_of_response:1; }","duration":"104.132567ms","start":"2026-04-27T21:30:55.132645Z","end":"2026-04-27T21:30:55.236778Z","steps":["trace[2013139891] 'process raft request' (duration: 103.731605ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:30:56.387902Z","caller":"traceutil/trace.go:171","msg":"trace[475026108] transaction","detail":"{read_only:false; response_revision:15879; number_of_response:1; }","duration":"149.883428ms","start":"2026-04-27T21:30:56.237994Z","end":"2026-04-27T21:30:56.387878Z","steps":["trace[475026108] 'process raft request' (duration: 149.568289ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:33:42.84164Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14623} {"level":"info","ts":"2026-04-27T21:33:42.931782Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14623,"took":"82.786559ms","hash":3455670985,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":33329152,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-27T21:33:42.931902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3455670985,"revision":14623,"compact-revision":12593} {"level":"info","ts":"2026-04-27T21:36:34.890026Z","caller":"traceutil/trace.go:171","msg":"trace[1306760515] transaction","detail":"{read_only:false; response_revision:18409; number_of_response:1; }","duration":"235.763513ms","start":"2026-04-27T21:36:34.654232Z","end":"2026-04-27T21:36:34.889996Z","steps":["trace[1306760515] 'process raft request' (duration: 235.411243ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:36:34.904791Z","caller":"traceutil/trace.go:171","msg":"trace[908388194] linearizableReadLoop","detail":"{readStateIndex:19045; appliedIndex:19042; }","duration":"194.580369ms","start":"2026-04-27T21:36:34.710187Z","end":"2026-04-27T21:36:34.904767Z","steps":["trace[908388194] 'read index received' (duration: 179.465946ms)","trace[908388194] 'applied index is now lower than readState.Index' (duration: 15.113523ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:36:34.904857Z","caller":"traceutil/trace.go:171","msg":"trace[1725757114] transaction","detail":"{read_only:false; response_revision:18410; number_of_response:1; }","duration":"248.156441ms","start":"2026-04-27T21:36:34.656664Z","end":"2026-04-27T21:36:34.904821Z","steps":["trace[1725757114] 'process raft request' (duration: 247.951444ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:36:34.904946Z","caller":"traceutil/trace.go:171","msg":"trace[2075189229] transaction","detail":"{read_only:false; response_revision:18411; number_of_response:1; }","duration":"247.628226ms","start":"2026-04-27T21:36:34.657244Z","end":"2026-04-27T21:36:34.904872Z","steps":["trace[2075189229] 'process raft request' (duration: 247.482031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:36:34.905055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.851038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-04-27T21:36:34.905137Z","caller":"traceutil/trace.go:171","msg":"trace[2064387422] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:18411; }","duration":"194.976641ms","start":"2026-04-27T21:36:34.710143Z","end":"2026-04-27T21:36:34.90512Z","steps":["trace[2064387422] 'agreement among raft nodes before linearized reading' (duration: 194.783506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:36:35.217263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.706669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:36:35.217485Z","caller":"traceutil/trace.go:171","msg":"trace[1765909423] transaction","detail":"{read_only:false; response_revision:18412; number_of_response:1; }","duration":"307.603666ms","start":"2026-04-27T21:36:34.909859Z","end":"2026-04-27T21:36:35.217463Z","steps":["trace[1765909423] 'process raft request' (duration: 180.611019ms)","trace[1765909423] 'compare' (duration: 126.242236ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:36:35.217612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:36:34.909847Z","time spent":"307.701468ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:36:35.217709Z","caller":"traceutil/trace.go:171","msg":"trace[1877929606] linearizableReadLoop","detail":"{readStateIndex:19047; appliedIndex:19045; }","duration":"298.936173ms","start":"2026-04-27T21:36:34.918716Z","end":"2026-04-27T21:36:35.217652Z","steps":["trace[1877929606] 'read index received' (duration: 171.7217ms)","trace[1877929606] 'applied index is now lower than readState.Index' (duration: 127.213393ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:36:35.217919Z","caller":"traceutil/trace.go:171","msg":"trace[1238649948] transaction","detail":"{read_only:false; response_revision:18413; number_of_response:1; }","duration":"307.907014ms","start":"2026-04-27T21:36:34.909998Z","end":"2026-04-27T21:36:35.217905Z","steps":["trace[1238649948] 'process raft request' (duration: 307.373309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:36:35.218305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.57252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-bfng4\" ","response":"range_response_count:1 size:22097"} {"level":"warn","ts":"2026-04-27T21:36:35.218285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.306358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:36:35.218353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.974502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-d4jzr\" ","response":"range_response_count:1 size:20853"} {"level":"info","ts":"2026-04-27T21:36:35.218392Z","caller":"traceutil/trace.go:171","msg":"trace[888294876] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-bfng4; range_end:; response_count:1; response_revision:18413; }","duration":"299.744125ms","start":"2026-04-27T21:36:34.918624Z","end":"2026-04-27T21:36:35.218368Z","steps":["trace[888294876] 'agreement among raft nodes before linearized reading' (duration: 299.462407ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:36:35.218405Z","caller":"traceutil/trace.go:171","msg":"trace[88804548] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:18413; }","duration":"291.467143ms","start":"2026-04-27T21:36:34.926913Z","end":"2026-04-27T21:36:35.21838Z","steps":["trace[88804548] 'agreement among raft nodes before linearized reading' (duration: 291.270818ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:36:35.218424Z","caller":"traceutil/trace.go:171","msg":"trace[1671300246] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-d4jzr; range_end:; response_count:1; response_revision:18413; }","duration":"129.075635ms","start":"2026-04-27T21:36:35.089327Z","end":"2026-04-27T21:36:35.218403Z","steps":["trace[1671300246] 'agreement among raft nodes before linearized reading' (duration: 128.88793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:36:35.218696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:36:34.909973Z","time spent":"308.664356ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:36:35.526698Z","caller":"traceutil/trace.go:171","msg":"trace[1088148853] transaction","detail":"{read_only:false; response_revision:18414; number_of_response:1; }","duration":"293.355866ms","start":"2026-04-27T21:36:35.233313Z","end":"2026-04-27T21:36:35.526669Z","steps":["trace[1088148853] 'process raft request' (duration: 293.180061ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:38:05.681924Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T21:38:05.686979Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-27T21:38:05.687058Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-27T21:38:42.854277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17160} {"level":"info","ts":"2026-04-27T21:38:42.950852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17160,"took":"92.957704ms","hash":4106066892,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":33984512,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-27T21:38:42.950904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4106066892,"revision":17160,"compact-revision":14623} {"level":"info","ts":"2026-04-27T21:42:08.18195Z","caller":"traceutil/trace.go:171","msg":"trace[638241722] transaction","detail":"{read_only:false; response_revision:21030; number_of_response:1; }","duration":"270.636799ms","start":"2026-04-27T21:42:07.911285Z","end":"2026-04-27T21:42:08.181922Z","steps":["trace[638241722] 'process raft request' (duration: 270.484375ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:42:08.182229Z","caller":"traceutil/trace.go:171","msg":"trace[926883435] linearizableReadLoop","detail":"{readStateIndex:21749; appliedIndex:21749; }","duration":"225.293988ms","start":"2026-04-27T21:42:07.956908Z","end":"2026-04-27T21:42:08.182202Z","steps":["trace[926883435] 'read index received' (duration: 225.284378ms)","trace[926883435] 'applied index is now lower than readState.Index' (duration: 8.09µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:42:08.182373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.438802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:42:08.182436Z","caller":"traceutil/trace.go:171","msg":"trace[1413754960] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:21030; }","duration":"225.554855ms","start":"2026-04-27T21:42:07.956864Z","end":"2026-04-27T21:42:08.182418Z","steps":["trace[1413754960] 'agreement among raft nodes before linearized reading' (duration: 225.436382ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:42:24.596045Z","caller":"traceutil/trace.go:171","msg":"trace[198960525] transaction","detail":"{read_only:false; response_revision:21118; number_of_response:1; }","duration":"256.112721ms","start":"2026-04-27T21:42:24.33991Z","end":"2026-04-27T21:42:24.596023Z","steps":["trace[198960525] 'process raft request' (duration: 255.877155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:42:24.596261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.840483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-27T21:42:24.596322Z","caller":"traceutil/trace.go:171","msg":"trace[2061390498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21118; }","duration":"245.983148ms","start":"2026-04-27T21:42:24.350321Z","end":"2026-04-27T21:42:24.596304Z","steps":["trace[2061390498] 'agreement among raft nodes before linearized reading' (duration: 245.770012ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:42:24.596013Z","caller":"traceutil/trace.go:171","msg":"trace[869224962] linearizableReadLoop","detail":"{readStateIndex:21841; appliedIndex:21840; }","duration":"245.600437ms","start":"2026-04-27T21:42:24.350378Z","end":"2026-04-27T21:42:24.595979Z","steps":["trace[869224962] 'read index received' (duration: 245.315299ms)","trace[869224962] 'applied index is now lower than readState.Index' (duration: 283.368µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:42:24.995473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.667691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:42:24.995583Z","caller":"traceutil/trace.go:171","msg":"trace[847572715] linearizableReadLoop","detail":"{readStateIndex:21843; appliedIndex:21842; }","duration":"252.021337ms","start":"2026-04-27T21:42:24.743546Z","end":"2026-04-27T21:42:24.995568Z","steps":["trace[847572715] 'read index received' (duration: 102.241913ms)","trace[847572715] 'applied index is now lower than readState.Index' (duration: 149.778613ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:42:24.995629Z","caller":"traceutil/trace.go:171","msg":"trace[673598447] transaction","detail":"{read_only:false; response_revision:21120; number_of_response:1; }","duration":"306.571474ms","start":"2026-04-27T21:42:24.689018Z","end":"2026-04-27T21:42:24.99559Z","steps":["trace[673598447] 'process raft request' (duration: 156.711878ms)","trace[673598447] 'compare' (duration: 137.616534ms)","trace[673598447] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; req_size:541; } (duration: 11.936344ms)"],"step_count":3} {"level":"warn","ts":"2026-04-27T21:42:24.995774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:42:24.688994Z","time spent":"306.709248ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:42:24.995777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.229623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-27T21:42:24.99582Z","caller":"traceutil/trace.go:171","msg":"trace[748515077] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:21120; }","duration":"252.309525ms","start":"2026-04-27T21:42:24.743503Z","end":"2026-04-27T21:42:24.995812Z","steps":["trace[748515077] 'agreement among raft nodes before linearized reading' (duration: 252.140681ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:42:25.175801Z","caller":"traceutil/trace.go:171","msg":"trace[1396356229] transaction","detail":"{read_only:false; response_revision:21122; number_of_response:1; }","duration":"139.155696ms","start":"2026-04-27T21:42:25.036616Z","end":"2026-04-27T21:42:25.175772Z","steps":["trace[1396356229] 'process raft request' (duration: 139.094445ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:42:25.176252Z","caller":"traceutil/trace.go:171","msg":"trace[173980953] transaction","detail":"{read_only:false; response_revision:21121; number_of_response:1; }","duration":"176.281195ms","start":"2026-04-27T21:42:24.999956Z","end":"2026-04-27T21:42:25.176237Z","steps":["trace[173980953] 'process raft request' (duration: 130.599096ms)","trace[173980953] 'compare' (duration: 44.924988ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:42:25.176422Z","caller":"traceutil/trace.go:171","msg":"trace[1739577124] linearizableReadLoop","detail":"{readStateIndex:21844; appliedIndex:21843; }","duration":"161.278087ms","start":"2026-04-27T21:42:25.015129Z","end":"2026-04-27T21:42:25.176407Z","steps":["trace[1739577124] 'read index received' (duration: 115.452683ms)","trace[1739577124] 'applied index is now lower than readState.Index' (duration: 45.824144ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:42:25.176755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.603156ms","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-27T21:42:25.17681Z","caller":"traceutil/trace.go:171","msg":"trace[1523323975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21122; }","duration":"161.704269ms","start":"2026-04-27T21:42:25.015091Z","end":"2026-04-27T21:42:25.176796Z","steps":["trace[1523323975] 'agreement among raft nodes before linearized reading' (duration: 161.537284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:42:25.177118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.821341ms","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-27T21:42:25.177168Z","caller":"traceutil/trace.go:171","msg":"trace[690735526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21122; }","duration":"161.922435ms","start":"2026-04-27T21:42:25.015232Z","end":"2026-04-27T21:42:25.177155Z","steps":["trace[690735526] 'agreement among raft nodes before linearized reading' (duration: 161.76673ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:43:42.859917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19601} {"level":"info","ts":"2026-04-27T21:43:42.970262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19601,"took":"105.901036ms","hash":3574741400,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":30130176,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-27T21:43:42.970373Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3574741400,"revision":19601,"compact-revision":17160} {"level":"info","ts":"2026-04-27T21:46:14.582457Z","caller":"traceutil/trace.go:171","msg":"trace[465852938] linearizableReadLoop","detail":"{readStateIndex:24341; appliedIndex:24340; }","duration":"170.73908ms","start":"2026-04-27T21:46:14.411681Z","end":"2026-04-27T21:46:14.58242Z","steps":["trace[465852938] 'read index received' (duration: 170.502974ms)","trace[465852938] 'applied index is now lower than readState.Index' (duration: 234.576µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:14.58292Z","caller":"traceutil/trace.go:171","msg":"trace[19629849] transaction","detail":"{read_only:false; response_revision:23546; number_of_response:1; }","duration":"271.881771ms","start":"2026-04-27T21:46:14.311002Z","end":"2026-04-27T21:46:14.582883Z","steps":["trace[19629849] 'process raft request' (duration: 271.228413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:14.583009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.325457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-27T21:46:14.583065Z","caller":"traceutil/trace.go:171","msg":"trace[1081981984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23546; }","duration":"171.44327ms","start":"2026-04-27T21:46:14.411607Z","end":"2026-04-27T21:46:14.583051Z","steps":["trace[1081981984] 'agreement among raft nodes before linearized reading' (duration: 171.244564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:14.583149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.329546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T21:46:14.583366Z","caller":"traceutil/trace.go:171","msg":"trace[722527427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23546; }","duration":"171.717888ms","start":"2026-04-27T21:46:14.411624Z","end":"2026-04-27T21:46:14.583342Z","steps":["trace[722527427] 'agreement among raft nodes before linearized reading' (duration: 171.218584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:14.795696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.758363ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:46:14.795839Z","caller":"traceutil/trace.go:171","msg":"trace[315652715] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:23546; }","duration":"124.929458ms","start":"2026-04-27T21:46:14.67089Z","end":"2026-04-27T21:46:14.795819Z","steps":["trace[315652715] 'range keys from in-memory index tree' (duration: 124.736902ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:46:14.796815Z","caller":"traceutil/trace.go:171","msg":"trace[1521319786] transaction","detail":"{read_only:false; response_revision:23547; number_of_response:1; }","duration":"209.105103ms","start":"2026-04-27T21:46:14.587669Z","end":"2026-04-27T21:46:14.796774Z","steps":["trace[1521319786] 'process raft request' (duration: 138.219759ms)","trace[1521319786] 'compare' (duration: 69.965989ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:14.797063Z","caller":"traceutil/trace.go:171","msg":"trace[840142837] linearizableReadLoop","detail":"{readStateIndex:24343; appliedIndex:24341; }","duration":"116.886451ms","start":"2026-04-27T21:46:14.680136Z","end":"2026-04-27T21:46:14.797022Z","steps":["trace[840142837] 'read index received' (duration: 45.871364ms)","trace[840142837] 'applied index is now lower than readState.Index' (duration: 71.012587ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:46:14.797221Z","caller":"traceutil/trace.go:171","msg":"trace[1116235917] transaction","detail":"{read_only:false; response_revision:23548; number_of_response:1; }","duration":"209.222806ms","start":"2026-04-27T21:46:14.587971Z","end":"2026-04-27T21:46:14.797194Z","steps":["trace[1116235917] 'process raft request' (duration: 208.735673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:46:14.797334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.18498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:46:14.797404Z","caller":"traceutil/trace.go:171","msg":"trace[1616367364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23548; }","duration":"117.315903ms","start":"2026-04-27T21:46:14.680069Z","end":"2026-04-27T21:46:14.797385Z","steps":["trace[1616367364] 'agreement among raft nodes before linearized reading' (duration: 117.156609ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:43.597706Z","caller":"traceutil/trace.go:171","msg":"trace[549985658] linearizableReadLoop","detail":"{readStateIndex:25314; appliedIndex:25311; }","duration":"104.68896ms","start":"2026-04-27T21:47:43.492986Z","end":"2026-04-27T21:47:43.597675Z","steps":["trace[549985658] 'read index received' (duration: 58.859818ms)","trace[549985658] 'applied index is now lower than readState.Index' (duration: 45.828142ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:47:43.597755Z","caller":"traceutil/trace.go:171","msg":"trace[989307964] transaction","detail":"{read_only:false; response_revision:24495; number_of_response:1; }","duration":"106.309216ms","start":"2026-04-27T21:47:43.491403Z","end":"2026-04-27T21:47:43.597712Z","steps":["trace[989307964] 'process raft request' (duration: 106.06357ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:47:43.597887Z","caller":"traceutil/trace.go:171","msg":"trace[1796390380] transaction","detail":"{read_only:false; response_revision:24496; number_of_response:1; }","duration":"105.628947ms","start":"2026-04-27T21:47:43.492224Z","end":"2026-04-27T21:47:43.597853Z","steps":["trace[1796390380] 'process raft request' (duration: 105.389481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:47:43.597987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.977478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7566fcc474-8c6jv.18aa532ed10ed434\" ","response":"range_response_count:1 size:1137"} {"level":"info","ts":"2026-04-27T21:47:43.598042Z","caller":"traceutil/trace.go:171","msg":"trace[1044831859] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7566fcc474-8c6jv.18aa532ed10ed434; range_end:; response_count:1; response_revision:24496; }","duration":"105.071701ms","start":"2026-04-27T21:47:43.492956Z","end":"2026-04-27T21:47:43.598028Z","steps":["trace[1044831859] 'agreement among raft nodes before linearized reading' (duration: 104.845165ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:48:42.865539Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21844} {"level":"info","ts":"2026-04-27T21:48:42.970506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21844,"took":"100.760771ms","hash":3047383039,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":43122688,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-27T21:48:42.970588Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3047383039,"revision":21844,"compact-revision":19601} {"level":"info","ts":"2026-04-27T21:49:42.361333Z","caller":"traceutil/trace.go:171","msg":"trace[1364549789] linearizableReadLoop","detail":"{readStateIndex:26479; appliedIndex:26478; }","duration":"380.695368ms","start":"2026-04-27T21:49:41.980608Z","end":"2026-04-27T21:49:42.361304Z","steps":["trace[1364549789] 'read index received' (duration: 380.320107ms)","trace[1364549789] 'applied index is now lower than readState.Index' (duration: 374.271µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:49:42.361384Z","caller":"traceutil/trace.go:171","msg":"trace[306502923] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"403.732632ms","start":"2026-04-27T21:49:41.957617Z","end":"2026-04-27T21:49:42.361349Z","steps":["trace[306502923] 'process raft request' (duration: 403.332651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:42.361555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:41.957604Z","time spent":"403.863796ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:49:42.361572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.596423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:49:42.361661Z","caller":"traceutil/trace.go:171","msg":"trace[1556775642] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:25629; }","duration":"386.730777ms","start":"2026-04-27T21:49:41.974907Z","end":"2026-04-27T21:49:42.361638Z","steps":["trace[1556775642] 'agreement among raft nodes before linearized reading' (duration: 386.560302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:42.361727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:41.974897Z","time spent":"386.813089ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":6,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:49:42.416183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.37799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:49:42.416263Z","caller":"traceutil/trace.go:171","msg":"trace[403770522] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:25632; }","duration":"429.466312ms","start":"2026-04-27T21:49:41.986781Z","end":"2026-04-27T21:49:42.416247Z","steps":["trace[403770522] 'agreement among raft nodes before linearized reading' (duration: 429.330479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:42.416299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:41.986765Z","time spent":"429.525254ms","remote":"127.0.0.1:39696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-27T21:49:42.416509Z","caller":"traceutil/trace.go:171","msg":"trace[1789732968] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"434.678829ms","start":"2026-04-27T21:49:41.98182Z","end":"2026-04-27T21:49:42.416499Z","steps":["trace[1789732968] 'process raft request' (duration: 427.53417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:42.416581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:41.981807Z","time spent":"434.73059ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:49:42.416766Z","caller":"traceutil/trace.go:171","msg":"trace[1131609571] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"414.73866ms","start":"2026-04-27T21:49:42.00202Z","end":"2026-04-27T21:49:42.416759Z","steps":["trace[1131609571] 'process raft request' (duration: 413.99665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:42.416812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:42.002005Z","time spent":"414.786022ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:49:42.416909Z","caller":"traceutil/trace.go:171","msg":"trace[1944075851] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"413.328572ms","start":"2026-04-27T21:49:42.003571Z","end":"2026-04-27T21:49:42.4169Z","steps":["trace[1944075851] 'process raft request' (duration: 412.493198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:42.416959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:42.003555Z","time spent":"413.382713ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:49:42.417123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.318968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:49:42.417155Z","caller":"traceutil/trace.go:171","msg":"trace[1944378426] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25632; }","duration":"250.443852ms","start":"2026-04-27T21:49:42.166703Z","end":"2026-04-27T21:49:42.417147Z","steps":["trace[1944378426] 'agreement among raft nodes before linearized reading' (duration: 250.347109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:42.41729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.941447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:49:42.417316Z","caller":"traceutil/trace.go:171","msg":"trace[864666629] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25632; }","duration":"405.013069ms","start":"2026-04-27T21:49:42.012297Z","end":"2026-04-27T21:49:42.41731Z","steps":["trace[864666629] 'agreement among raft nodes before linearized reading' (duration: 404.968957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:42.417336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:42.012277Z","time spent":"405.05271ms","remote":"127.0.0.1:51642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"info","ts":"2026-04-27T21:49:42.624254Z","caller":"traceutil/trace.go:171","msg":"trace[922772612] transaction","detail":"{read_only:false; response_revision:25633; number_of_response:1; }","duration":"128.061645ms","start":"2026-04-27T21:49:42.496151Z","end":"2026-04-27T21:49:42.624213Z","steps":["trace[922772612] 'process raft request' (duration: 127.817138ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:46.556209Z","caller":"traceutil/trace.go:171","msg":"trace[10319529] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"122.337605ms","start":"2026-04-27T21:49:46.433843Z","end":"2026-04-27T21:49:46.556181Z","steps":["trace[10319529] 'process raft request' (duration: 116.654626ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:46.561772Z","caller":"traceutil/trace.go:171","msg":"trace[24107776] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"127.389796ms","start":"2026-04-27T21:49:46.434352Z","end":"2026-04-27T21:49:46.561742Z","steps":["trace[24107776] 'process raft request' (duration: 127.210961ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:46.561923Z","caller":"traceutil/trace.go:171","msg":"trace[1680593053] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"127.416757ms","start":"2026-04-27T21:49:46.43448Z","end":"2026-04-27T21:49:46.561897Z","steps":["trace[1680593053] 'process raft request' (duration: 127.209131ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:46.933312Z","caller":"traceutil/trace.go:171","msg":"trace[1587313312] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"135.740031ms","start":"2026-04-27T21:49:46.797525Z","end":"2026-04-27T21:49:46.933265Z","steps":["trace[1587313312] 'process raft request' (duration: 135.429543ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:46.960305Z","caller":"traceutil/trace.go:171","msg":"trace[530935057] linearizableReadLoop","detail":"{readStateIndex:26520; appliedIndex:26518; }","duration":"142.119698ms","start":"2026-04-27T21:49:46.818153Z","end":"2026-04-27T21:49:46.960272Z","steps":["trace[530935057] 'read index received' (duration: 114.843425ms)","trace[530935057] 'applied index is now lower than readState.Index' (duration: 27.275083ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:49:46.960436Z","caller":"traceutil/trace.go:171","msg":"trace[286872022] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"161.153382ms","start":"2026-04-27T21:49:46.799229Z","end":"2026-04-27T21:49:46.960382Z","steps":["trace[286872022] 'process raft request' (duration: 160.885684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:46.960559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.396086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-27T21:49:46.960599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.828239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-27T21:49:46.960644Z","caller":"traceutil/trace.go:171","msg":"trace[624002910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25669; }","duration":"142.536909ms","start":"2026-04-27T21:49:46.81809Z","end":"2026-04-27T21:49:46.960627Z","steps":["trace[624002910] 'agreement among raft nodes before linearized reading' (duration: 142.327564ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:46.960646Z","caller":"traceutil/trace.go:171","msg":"trace[1420011580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25669; }","duration":"137.912021ms","start":"2026-04-27T21:49:46.822721Z","end":"2026-04-27T21:49:46.960633Z","steps":["trace[1420011580] 'agreement among raft nodes before linearized reading' (duration: 137.723125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:47.241205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.954225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:49:47.241501Z","caller":"traceutil/trace.go:171","msg":"trace[2134034557] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"276.476031ms","start":"2026-04-27T21:49:46.964992Z","end":"2026-04-27T21:49:47.241468Z","steps":["trace[2134034557] 'process raft request' (duration: 124.179576ms)","trace[2134034557] 'compare' (duration: 151.78112ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:49:47.241511Z","caller":"traceutil/trace.go:171","msg":"trace[1559409419] transaction","detail":"{read_only:false; response_revision:25671; number_of_response:1; }","duration":"275.492304ms","start":"2026-04-27T21:49:46.965982Z","end":"2026-04-27T21:49:47.241474Z","steps":["trace[1559409419] 'process raft request' (duration: 275.326939ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:47.926722Z","caller":"traceutil/trace.go:171","msg":"trace[151076672] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"225.567734ms","start":"2026-04-27T21:49:47.701123Z","end":"2026-04-27T21:49:47.926691Z","steps":["trace[151076672] 'process raft request' (duration: 225.41544ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:47.952014Z","caller":"traceutil/trace.go:171","msg":"trace[73287014] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"160.555044ms","start":"2026-04-27T21:49:47.791427Z","end":"2026-04-27T21:49:47.951982Z","steps":["trace[73287014] 'process raft request' (duration: 160.289497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:48.223602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.575956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:49:48.223736Z","caller":"traceutil/trace.go:171","msg":"trace[955130961] linearizableReadLoop","detail":"{readStateIndex:26526; appliedIndex:26525; }","duration":"214.050852ms","start":"2026-04-27T21:49:48.009664Z","end":"2026-04-27T21:49:48.223715Z","steps":["trace[955130961] 'read index received' (duration: 88.114227ms)","trace[955130961] 'applied index is now lower than readState.Index' (duration: 125.935295ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:49:48.223967Z","caller":"traceutil/trace.go:171","msg":"trace[1728293751] transaction","detail":"{read_only:false; response_revision:25675; number_of_response:1; }","duration":"257.22569ms","start":"2026-04-27T21:49:47.9667Z","end":"2026-04-27T21:49:48.223926Z","steps":["trace[1728293751] 'process raft request' (duration: 131.168111ms)","trace[1728293751] 'compare' (duration: 125.445642ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:49:48.224264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.600688ms","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-27T21:49:48.22431Z","caller":"traceutil/trace.go:171","msg":"trace[580181718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25675; }","duration":"214.68151ms","start":"2026-04-27T21:49:48.009617Z","end":"2026-04-27T21:49:48.224298Z","steps":["trace[580181718] 'agreement among raft nodes before linearized reading' (duration: 214.205516ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:48.460669Z","caller":"traceutil/trace.go:171","msg":"trace[125052729] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"232.578691ms","start":"2026-04-27T21:49:48.22804Z","end":"2026-04-27T21:49:48.460619Z","steps":["trace[125052729] 'process raft request' (duration: 151.503561ms)","trace[125052729] 'compare' (duration: 80.844414ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:49:48.649539Z","caller":"traceutil/trace.go:171","msg":"trace[624851303] linearizableReadLoop","detail":"{readStateIndex:26529; appliedIndex:26528; }","duration":"188.937129ms","start":"2026-04-27T21:49:48.460565Z","end":"2026-04-27T21:49:48.649502Z","steps":["trace[624851303] 'read index received' (duration: 188.723343ms)","trace[624851303] 'applied index is now lower than readState.Index' (duration: 212.415µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:49:48.649718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.00796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:49:48.649755Z","caller":"traceutil/trace.go:171","msg":"trace[377170568] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25678; }","duration":"264.092743ms","start":"2026-04-27T21:49:48.385653Z","end":"2026-04-27T21:49:48.649746Z","steps":["trace[377170568] 'agreement among raft nodes before linearized reading' (duration: 263.97691ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:48.649859Z","caller":"traceutil/trace.go:171","msg":"trace[2111799108] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"249.458084ms","start":"2026-04-27T21:49:48.400368Z","end":"2026-04-27T21:49:48.649826Z","steps":["trace[2111799108] 'process raft request' (duration: 248.932659ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:48.922216Z","caller":"traceutil/trace.go:171","msg":"trace[636358782] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"457.006224ms","start":"2026-04-27T21:49:48.465175Z","end":"2026-04-27T21:49:48.922181Z","steps":["trace[636358782] 'process raft request' (duration: 374.089262ms)","trace[636358782] 'compare' (duration: 82.369286ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:49:48.922373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:48.465153Z","time spent":"457.144187ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:49:48.922495Z","caller":"traceutil/trace.go:171","msg":"trace[949550447] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"360.884484ms","start":"2026-04-27T21:49:48.561575Z","end":"2026-04-27T21:49:48.92246Z","steps":["trace[949550447] 'process raft request' (duration: 360.551634ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:48.922573Z","caller":"traceutil/trace.go:171","msg":"trace[1104747406] transaction","detail":"{read_only:false; response_revision:25681; number_of_response:1; }","duration":"357.442137ms","start":"2026-04-27T21:49:48.565117Z","end":"2026-04-27T21:49:48.922559Z","steps":["trace[1104747406] 'process raft request' (duration: 357.252012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:48.922617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:48.561547Z","time spent":"361.007447ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:49:48.922645Z","caller":"traceutil/trace.go:171","msg":"trace[893679014] linearizableReadLoop","detail":"{readStateIndex:26533; appliedIndex:26529; }","duration":"243.072085ms","start":"2026-04-27T21:49:48.67956Z","end":"2026-04-27T21:49:48.922632Z","steps":["trace[893679014] 'read index received' (duration: 159.700101ms)","trace[893679014] 'applied index is now lower than readState.Index' (duration: 83.370464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:49:48.922656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:48.565106Z","time spent":"357.504849ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:49:48.922762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.219839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:49:48.922801Z","caller":"traceutil/trace.go:171","msg":"trace[839495191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25682; }","duration":"243.317482ms","start":"2026-04-27T21:49:48.679473Z","end":"2026-04-27T21:49:48.922791Z","steps":["trace[839495191] 'agreement among raft nodes before linearized reading' (duration: 243.25713ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:48.922774Z","caller":"traceutil/trace.go:171","msg":"trace[512626337] transaction","detail":"{read_only:false; response_revision:25682; number_of_response:1; }","duration":"355.391589ms","start":"2026-04-27T21:49:48.567341Z","end":"2026-04-27T21:49:48.922732Z","steps":["trace[512626337] 'process raft request' (duration: 355.173543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:48.923486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:48.567329Z","time spent":"355.569955ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:49:49.230531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.061841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:49:49.230647Z","caller":"traceutil/trace.go:171","msg":"trace[486309228] linearizableReadLoop","detail":"{readStateIndex:26534; appliedIndex:26533; }","duration":"282.311984ms","start":"2026-04-27T21:49:48.948317Z","end":"2026-04-27T21:49:49.230629Z","steps":["trace[486309228] 'read index received' (duration: 132.074218ms)","trace[486309228] 'applied index is now lower than readState.Index' (duration: 150.236346ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:49:49.230725Z","caller":"traceutil/trace.go:171","msg":"trace[324804863] transaction","detail":"{read_only:false; response_revision:25683; number_of_response:1; }","duration":"291.896791ms","start":"2026-04-27T21:49:48.938773Z","end":"2026-04-27T21:49:49.23067Z","steps":["trace[324804863] 'process raft request' (duration: 141.614304ms)","trace[324804863] 'compare' (duration: 149.761943ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:49:49.230893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.55801ms","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-27T21:49:49.231007Z","caller":"traceutil/trace.go:171","msg":"trace[1694479642] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25683; }","duration":"282.734366ms","start":"2026-04-27T21:49:48.948249Z","end":"2026-04-27T21:49:49.230984Z","steps":["trace[1694479642] 'agreement among raft nodes before linearized reading' (duration: 282.426877ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:49.231766Z","caller":"traceutil/trace.go:171","msg":"trace[34905121] transaction","detail":"{read_only:false; response_revision:25684; number_of_response:1; }","duration":"266.237363ms","start":"2026-04-27T21:49:48.965509Z","end":"2026-04-27T21:49:49.231746Z","steps":["trace[34905121] 'process raft request' (duration: 266.098189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:49.559964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.093432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:49:49.560113Z","caller":"traceutil/trace.go:171","msg":"trace[2117227891] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"309.670109ms","start":"2026-04-27T21:49:49.250409Z","end":"2026-04-27T21:49:49.560079Z","steps":["trace[2117227891] 'process raft request' (duration: 174.402832ms)","trace[2117227891] 'compare' (duration: 134.584728ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:49:49.560191Z","caller":"traceutil/trace.go:171","msg":"trace[1328260094] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"256.037397ms","start":"2026-04-27T21:49:49.304134Z","end":"2026-04-27T21:49:49.560172Z","steps":["trace[1328260094] 'process raft request' (duration: 255.950815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:49.56027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:49.250391Z","time spent":"309.801393ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:49:49.560442Z","caller":"traceutil/trace.go:171","msg":"trace[454571869] linearizableReadLoop","detail":"{readStateIndex:26537; appliedIndex:26536; }","duration":"261.367308ms","start":"2026-04-27T21:49:49.299037Z","end":"2026-04-27T21:49:49.560404Z","steps":["trace[454571869] 'read index received' (duration: 125.899894ms)","trace[454571869] 'applied index is now lower than readState.Index' (duration: 135.466314ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:49:49.560599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.034031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:49:49.560637Z","caller":"traceutil/trace.go:171","msg":"trace[267219936] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25687; }","duration":"270.105763ms","start":"2026-04-27T21:49:49.290522Z","end":"2026-04-27T21:49:49.560628Z","steps":["trace[267219936] 'agreement among raft nodes before linearized reading' (duration: 270.0031ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:50.271639Z","caller":"traceutil/trace.go:171","msg":"trace[1709498716] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"315.100911ms","start":"2026-04-27T21:49:49.956514Z","end":"2026-04-27T21:49:50.271615Z","steps":["trace[1709498716] 'process raft request' (duration: 314.994928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:50.271784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:49.956475Z","time spent":"315.236265ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:49:50.272068Z","caller":"traceutil/trace.go:171","msg":"trace[1147680462] linearizableReadLoop","detail":"{readStateIndex:26541; appliedIndex:26539; }","duration":"111.494812ms","start":"2026-04-27T21:49:50.160556Z","end":"2026-04-27T21:49:50.272051Z","steps":["trace[1147680462] 'read index received' (duration: 110.949826ms)","trace[1147680462] 'applied index is now lower than readState.Index' (duration: 543.996µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:49:50.272142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.585474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:49:50.272169Z","caller":"traceutil/trace.go:171","msg":"trace[2014787641] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25690; }","duration":"111.653676ms","start":"2026-04-27T21:49:50.160509Z","end":"2026-04-27T21:49:50.272162Z","steps":["trace[2014787641] 'agreement among raft nodes before linearized reading' (duration: 111.602404ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:50.272084Z","caller":"traceutil/trace.go:171","msg":"trace[172415477] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"140.256687ms","start":"2026-04-27T21:49:50.131818Z","end":"2026-04-27T21:49:50.272074Z","steps":["trace[172415477] 'process raft request' (duration: 140.166405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:50.612221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.009276ms","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-27T21:49:50.612294Z","caller":"traceutil/trace.go:171","msg":"trace[450834348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25691; }","duration":"147.112719ms","start":"2026-04-27T21:49:50.465166Z","end":"2026-04-27T21:49:50.612278Z","steps":["trace[450834348] 'range keys from in-memory index tree' (duration: 146.847802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:50.612364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.26382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:49:50.612464Z","caller":"traceutil/trace.go:171","msg":"trace[125759801] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:25691; }","duration":"193.416685ms","start":"2026-04-27T21:49:50.419022Z","end":"2026-04-27T21:49:50.612439Z","steps":["trace[125759801] 'count revisions from in-memory index tree' (duration: 193.169648ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:50.753173Z","caller":"traceutil/trace.go:171","msg":"trace[1166898076] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"136.77977ms","start":"2026-04-27T21:49:50.616361Z","end":"2026-04-27T21:49:50.75314Z","steps":["trace[1166898076] 'process raft request' (duration: 136.537863ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:51.166962Z","caller":"traceutil/trace.go:171","msg":"trace[1093029527] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"238.386264ms","start":"2026-04-27T21:49:50.92853Z","end":"2026-04-27T21:49:51.166916Z","steps":["trace[1093029527] 'process raft request' (duration: 204.633469ms)","trace[1093029527] 'compare' (duration: 33.544509ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:49:51.16732Z","caller":"traceutil/trace.go:171","msg":"trace[1881475563] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"238.358943ms","start":"2026-04-27T21:49:50.928923Z","end":"2026-04-27T21:49:51.167282Z","steps":["trace[1881475563] 'process raft request' (duration: 237.936321ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:51.167569Z","caller":"traceutil/trace.go:171","msg":"trace[1700985697] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"216.918162ms","start":"2026-04-27T21:49:50.950637Z","end":"2026-04-27T21:49:51.167555Z","steps":["trace[1700985697] 'process raft request' (duration: 216.568423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:51.600872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.796948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:49:51.601438Z","caller":"traceutil/trace.go:171","msg":"trace[1891414012] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25699; }","duration":"133.438586ms","start":"2026-04-27T21:49:51.467979Z","end":"2026-04-27T21:49:51.601417Z","steps":["trace[1891414012] 'count revisions from in-memory index tree' (duration: 132.705566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:51.828604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.021803ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T21:49:51.959725Z","caller":"traceutil/trace.go:171","msg":"trace[931800115] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"354.703171ms","start":"2026-04-27T21:49:51.604988Z","end":"2026-04-27T21:49:51.959691Z","steps":["trace[931800115] 'process raft request' (duration: 354.612948ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:49:51.959749Z","caller":"traceutil/trace.go:171","msg":"trace[644379563] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"360.071911ms","start":"2026-04-27T21:49:51.599627Z","end":"2026-04-27T21:49:51.959699Z","steps":["trace[644379563] 'process raft request' (duration: 359.785533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:51.959896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:51.604943Z","time spent":"354.871895ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:49:51.959954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:51.599609Z","time spent":"360.240956ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:49:51.95997Z","caller":"traceutil/trace.go:171","msg":"trace[1254030406] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"354.095933ms","start":"2026-04-27T21:49:51.605815Z","end":"2026-04-27T21:49:51.959911Z","steps":["trace[1254030406] 'process raft request' (duration: 353.836976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:49:51.960144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:49:51.6058Z","time spent":"354.251917ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:49:52.264478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.834725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:49:52.264582Z","caller":"traceutil/trace.go:171","msg":"trace[1893921661] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"253.22802ms","start":"2026-04-27T21:49:52.011337Z","end":"2026-04-27T21:49:52.264565Z","steps":["trace[1893921661] 'process raft request' (duration: 103.239031ms)","trace[1893921661] 'compare' (duration: 149.696541ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:49:52.424848Z","caller":"traceutil/trace.go:171","msg":"trace[1548696250] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"147.300425ms","start":"2026-04-27T21:49:52.277524Z","end":"2026-04-27T21:49:52.424825Z","steps":["trace[1548696250] 'process raft request' (duration: 131.78721ms)","trace[1548696250] 'compare' (duration: 15.201966ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:00.695381Z","caller":"traceutil/trace.go:171","msg":"trace[1314936413] linearizableReadLoop","detail":"{readStateIndex:26626; appliedIndex:26625; }","duration":"154.812004ms","start":"2026-04-27T21:50:00.540542Z","end":"2026-04-27T21:50:00.695354Z","steps":["trace[1314936413] 'read index received' (duration: 154.636499ms)","trace[1314936413] 'applied index is now lower than readState.Index' (duration: 174.705µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:00.695473Z","caller":"traceutil/trace.go:171","msg":"trace[291645601] transaction","detail":"{read_only:false; response_revision:25773; number_of_response:1; }","duration":"246.601814ms","start":"2026-04-27T21:50:00.448853Z","end":"2026-04-27T21:50:00.695455Z","steps":["trace[291645601] 'process raft request' (duration: 246.356707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:00.695621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.070791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-27T21:50:00.695655Z","caller":"traceutil/trace.go:171","msg":"trace[1509236356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25773; }","duration":"155.119282ms","start":"2026-04-27T21:50:00.540524Z","end":"2026-04-27T21:50:00.695644Z","steps":["trace[1509236356] 'agreement among raft nodes before linearized reading' (duration: 154.963498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:00.695684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.307076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:50:00.695785Z","caller":"traceutil/trace.go:171","msg":"trace[1458152081] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:25773; }","duration":"121.460939ms","start":"2026-04-27T21:50:00.574302Z","end":"2026-04-27T21:50:00.695763Z","steps":["trace[1458152081] 'agreement among raft nodes before linearized reading' (duration: 121.274924ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:00.966219Z","caller":"traceutil/trace.go:171","msg":"trace[1304656440] linearizableReadLoop","detail":"{readStateIndex:26627; appliedIndex:26626; }","duration":"161.457001ms","start":"2026-04-27T21:50:00.804738Z","end":"2026-04-27T21:50:00.966195Z","steps":["trace[1304656440] 'read index received' (duration: 147.182741ms)","trace[1304656440] 'applied index is now lower than readState.Index' (duration: 14.27374ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:50:00.96641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.666536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T21:50:00.966387Z","caller":"traceutil/trace.go:171","msg":"trace[1476548888] transaction","detail":"{read_only:false; response_revision:25774; number_of_response:1; }","duration":"267.047486ms","start":"2026-04-27T21:50:00.6993Z","end":"2026-04-27T21:50:00.966347Z","steps":["trace[1476548888] 'process raft request' (duration: 252.682394ms)","trace[1476548888] 'compare' (duration: 14.135206ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:00.966442Z","caller":"traceutil/trace.go:171","msg":"trace[1480326011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25774; }","duration":"161.749349ms","start":"2026-04-27T21:50:00.804683Z","end":"2026-04-27T21:50:00.966432Z","steps":["trace[1480326011] 'agreement among raft nodes before linearized reading' (duration: 161.598504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:01.236913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.336279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:50:01.237024Z","caller":"traceutil/trace.go:171","msg":"trace[1704040945] transaction","detail":"{read_only:false; response_revision:25775; number_of_response:1; }","duration":"265.859403ms","start":"2026-04-27T21:50:00.971147Z","end":"2026-04-27T21:50:01.237006Z","steps":["trace[1704040945] 'process raft request' (duration: 115.354329ms)","trace[1704040945] 'compare' (duration: 150.02905ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:01.38879Z","caller":"traceutil/trace.go:171","msg":"trace[1496008034] linearizableReadLoop","detail":"{readStateIndex:26629; appliedIndex:26628; }","duration":"201.45685ms","start":"2026-04-27T21:50:01.187299Z","end":"2026-04-27T21:50:01.388756Z","steps":["trace[1496008034] 'read index received' (duration: 200.887064ms)","trace[1496008034] 'applied index is now lower than readState.Index' (duration: 568.446µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:01.38892Z","caller":"traceutil/trace.go:171","msg":"trace[1526470966] transaction","detail":"{read_only:false; response_revision:25776; number_of_response:1; }","duration":"395.329667ms","start":"2026-04-27T21:50:00.993549Z","end":"2026-04-27T21:50:01.388878Z","steps":["trace[1526470966] 'process raft request' (duration: 394.625348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:01.389016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.669395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:01.389185Z","caller":"traceutil/trace.go:171","msg":"trace[347335860] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25776; }","duration":"201.919502ms","start":"2026-04-27T21:50:01.187243Z","end":"2026-04-27T21:50:01.389162Z","steps":["trace[347335860] 'agreement among raft nodes before linearized reading' (duration: 201.659825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:01.389076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:00.993528Z","time spent":"395.46803ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:50:01.75765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.078257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:50:01.757797Z","caller":"traceutil/trace.go:171","msg":"trace[3826318] transaction","detail":"{read_only:false; response_revision:25777; number_of_response:1; }","duration":"556.996483ms","start":"2026-04-27T21:50:01.200784Z","end":"2026-04-27T21:50:01.75778Z","steps":["trace[3826318] 'process raft request' (duration: 304.718009ms)","trace[3826318] 'compare' (duration: 251.984905ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:50:01.757849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:01.200766Z","time spent":"557.059525ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:01.758256Z","caller":"traceutil/trace.go:171","msg":"trace[1821016298] transaction","detail":"{read_only:false; response_revision:25778; number_of_response:1; }","duration":"557.330383ms","start":"2026-04-27T21:50:01.200901Z","end":"2026-04-27T21:50:01.758231Z","steps":["trace[1821016298] 'process raft request' (duration: 556.838049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:01.75836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:01.200874Z","time spent":"557.434646ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:01.758387Z","caller":"traceutil/trace.go:171","msg":"trace[1325394959] transaction","detail":"{read_only:false; response_revision:25779; number_of_response:1; }","duration":"412.697464ms","start":"2026-04-27T21:50:01.345631Z","end":"2026-04-27T21:50:01.758328Z","steps":["trace[1325394959] 'process raft request' (duration: 412.493519ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:01.758476Z","caller":"traceutil/trace.go:171","msg":"trace[1795532386] transaction","detail":"{read_only:false; response_revision:25781; number_of_response:1; }","duration":"396.764428ms","start":"2026-04-27T21:50:01.361677Z","end":"2026-04-27T21:50:01.758441Z","steps":["trace[1795532386] 'process raft request' (duration: 396.619204ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:01.758509Z","caller":"traceutil/trace.go:171","msg":"trace[1322677509] transaction","detail":"{read_only:false; response_revision:25780; number_of_response:1; }","duration":"399.750041ms","start":"2026-04-27T21:50:01.358712Z","end":"2026-04-27T21:50:01.758462Z","steps":["trace[1322677509] 'process raft request' (duration: 399.514884ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:01.758548Z","caller":"traceutil/trace.go:171","msg":"trace[1801020385] linearizableReadLoop","detail":"{readStateIndex:26635; appliedIndex:26629; }","duration":"369.653639ms","start":"2026-04-27T21:50:01.388876Z","end":"2026-04-27T21:50:01.75853Z","steps":["trace[1801020385] 'read index received' (duration: 116.537452ms)","trace[1801020385] 'applied index is now lower than readState.Index' (duration: 253.115097ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:50:01.7586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:01.345611Z","time spent":"412.8808ms","remote":"127.0.0.1:45248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2297,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T21:50:01.75867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:01.358697Z","time spent":"399.888115ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:01.758715Z","caller":"traceutil/trace.go:171","msg":"trace[542475514] transaction","detail":"{read_only:false; response_revision:25782; number_of_response:1; }","duration":"393.875058ms","start":"2026-04-27T21:50:01.364826Z","end":"2026-04-27T21:50:01.758701Z","steps":["trace[542475514] 'process raft request' (duration: 393.570509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:01.758722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.496601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-27T21:50:01.758795Z","caller":"traceutil/trace.go:171","msg":"trace[2081791048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25782; }","duration":"555.561293ms","start":"2026-04-27T21:50:01.203186Z","end":"2026-04-27T21:50:01.758747Z","steps":["trace[2081791048] 'agreement among raft nodes before linearized reading' (duration: 555.407089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:01.758828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:01.20317Z","time spent":"555.650925ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T21:50:01.758824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:01.364813Z","time spent":"393.935569ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:50:01.75891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.265841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T21:50:01.758948Z","caller":"traceutil/trace.go:171","msg":"trace[421306651] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25782; }","duration":"437.344633ms","start":"2026-04-27T21:50:01.321591Z","end":"2026-04-27T21:50:01.758936Z","steps":["trace[421306651] 'agreement among raft nodes before linearized reading' (duration: 437.273201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:01.758996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:01.321558Z","time spent":"437.428826ms","remote":"127.0.0.1:46474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:50:01.758675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:01.361648Z","time spent":"396.913693ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:50:02.154361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.77333ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-04-27T21:50:02.338766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020034002059553,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T21:50:02.363569Z","caller":"traceutil/trace.go:171","msg":"trace[608972569] transaction","detail":"{read_only:false; response_revision:25783; number_of_response:1; }","duration":"597.834708ms","start":"2026-04-27T21:50:01.765704Z","end":"2026-04-27T21:50:02.363539Z","steps":["trace[608972569] 'process raft request' (duration: 597.523659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:02.363744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:01.765676Z","time spent":"597.988421ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:02.364186Z","caller":"traceutil/trace.go:171","msg":"trace[1660632522] transaction","detail":"{read_only:false; response_revision:25784; number_of_response:1; }","duration":"595.569814ms","start":"2026-04-27T21:50:01.768599Z","end":"2026-04-27T21:50:02.364169Z","steps":["trace[1660632522] 'process raft request' (duration: 594.844764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:02.364293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:01.768579Z","time spent":"595.683587ms","remote":"127.0.0.1:45220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2878,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T21:50:02.393065Z","caller":"traceutil/trace.go:171","msg":"trace[20365929] linearizableReadLoop","detail":"{readStateIndex:26639; appliedIndex:26636; }","duration":"555.08623ms","start":"2026-04-27T21:50:01.837955Z","end":"2026-04-27T21:50:02.393041Z","steps":["trace[20365929] 'read index received' (duration: 525.327138ms)","trace[20365929] 'applied index is now lower than readState.Index' (duration: 29.757332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:50:02.393243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.272255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:02.393314Z","caller":"traceutil/trace.go:171","msg":"trace[930210885] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25786; }","duration":"555.398019ms","start":"2026-04-27T21:50:01.837904Z","end":"2026-04-27T21:50:02.393302Z","steps":["trace[930210885] 'agreement among raft nodes before linearized reading' (duration: 555.286135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:02.393305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.289355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:02.39336Z","caller":"traceutil/trace.go:171","msg":"trace[452738606] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25786; }","duration":"116.396578ms","start":"2026-04-27T21:50:02.27695Z","end":"2026-04-27T21:50:02.393346Z","steps":["trace[452738606] 'agreement among raft nodes before linearized reading' (duration: 116.292285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:02.39339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:01.837885Z","time spent":"555.495322ms","remote":"127.0.0.1:46750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-04-27T21:50:02.393378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.51725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-27T21:50:02.39347Z","caller":"traceutil/trace.go:171","msg":"trace[483316306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25786; }","duration":"396.671905ms","start":"2026-04-27T21:50:01.99678Z","end":"2026-04-27T21:50:02.393452Z","steps":["trace[483316306] 'agreement among raft nodes before linearized reading' (duration: 396.452289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:02.393518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:01.996757Z","time spent":"396.753787ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T21:50:02.393689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.535147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-27T21:50:02.393953Z","caller":"traceutil/trace.go:171","msg":"trace[2147308942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25786; }","duration":"389.870556ms","start":"2026-04-27T21:50:02.004064Z","end":"2026-04-27T21:50:02.393934Z","steps":["trace[2147308942] 'agreement among raft nodes before linearized reading' (duration: 389.206607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:02.394234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:02.004041Z","time spent":"390.167383ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T21:50:02.778294Z","caller":"traceutil/trace.go:171","msg":"trace[1511851528] transaction","detail":"{read_only:false; response_revision:25787; number_of_response:1; }","duration":"380.40303ms","start":"2026-04-27T21:50:02.397856Z","end":"2026-04-27T21:50:02.778259Z","steps":["trace[1511851528] 'process raft request' (duration: 379.323699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:02.778468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:02.397839Z","time spent":"380.523923ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:02.792418Z","caller":"traceutil/trace.go:171","msg":"trace[854498803] transaction","detail":"{read_only:false; response_revision:25788; number_of_response:1; }","duration":"393.62512ms","start":"2026-04-27T21:50:02.398762Z","end":"2026-04-27T21:50:02.792387Z","steps":["trace[854498803] 'process raft request' (duration: 393.447215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:02.792583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:02.39874Z","time spent":"393.763825ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:02.792726Z","caller":"traceutil/trace.go:171","msg":"trace[79091127] linearizableReadLoop","detail":"{readStateIndex:26642; appliedIndex:26640; }","duration":"114.675441ms","start":"2026-04-27T21:50:02.677865Z","end":"2026-04-27T21:50:02.79254Z","steps":["trace[79091127] 'read index received' (duration: 99.438854ms)","trace[79091127] 'applied index is now lower than readState.Index' (duration: 15.234477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:50:02.792921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.04623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:02.793265Z","caller":"traceutil/trace.go:171","msg":"trace[1960039952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25789; }","duration":"115.151694ms","start":"2026-04-27T21:50:02.677805Z","end":"2026-04-27T21:50:02.792957Z","steps":["trace[1960039952] 'agreement among raft nodes before linearized reading' (duration: 115.029021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:03.12744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.182922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-27T21:50:03.127544Z","caller":"traceutil/trace.go:171","msg":"trace[72904041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25789; }","duration":"156.336917ms","start":"2026-04-27T21:50:02.971184Z","end":"2026-04-27T21:50:03.127521Z","steps":["trace[72904041] 'range keys from in-memory index tree' (duration: 155.921714ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:05.273016Z","caller":"traceutil/trace.go:171","msg":"trace[1685828305] transaction","detail":"{read_only:false; response_revision:25866; number_of_response:1; }","duration":"128.772065ms","start":"2026-04-27T21:50:05.144224Z","end":"2026-04-27T21:50:05.272996Z","steps":["trace[1685828305] 'process raft request' (duration: 128.398055ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:10.680492Z","caller":"traceutil/trace.go:171","msg":"trace[622923489] linearizableReadLoop","detail":"{readStateIndex:26785; appliedIndex:26784; }","duration":"290.74308ms","start":"2026-04-27T21:50:10.389723Z","end":"2026-04-27T21:50:10.680466Z","steps":["trace[622923489] 'read index received' (duration: 290.512783ms)","trace[622923489] 'applied index is now lower than readState.Index' (duration: 229.517µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:10.68059Z","caller":"traceutil/trace.go:171","msg":"trace[1630113473] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"382.052597ms","start":"2026-04-27T21:50:10.298495Z","end":"2026-04-27T21:50:10.680548Z","steps":["trace[1630113473] 'process raft request' (duration: 381.81144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:10.680762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.015327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-04-27T21:50:10.680776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:10.298479Z","time spent":"382.196ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:10.680831Z","caller":"traceutil/trace.go:171","msg":"trace[494780788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25929; }","duration":"291.096849ms","start":"2026-04-27T21:50:10.389717Z","end":"2026-04-27T21:50:10.680814Z","steps":["trace[494780788] 'agreement among raft nodes before linearized reading' (duration: 290.891434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:10.701868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.383451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-04-27T21:50:10.701981Z","caller":"traceutil/trace.go:171","msg":"trace[1291654168] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25930; }","duration":"234.541326ms","start":"2026-04-27T21:50:10.467412Z","end":"2026-04-27T21:50:10.701954Z","steps":["trace[1291654168] 'agreement among raft nodes before linearized reading' (duration: 234.262258ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:10.702467Z","caller":"traceutil/trace.go:171","msg":"trace[453908467] transaction","detail":"{read_only:false; response_revision:25930; number_of_response:1; }","duration":"287.843517ms","start":"2026-04-27T21:50:10.414604Z","end":"2026-04-27T21:50:10.702448Z","steps":["trace[453908467] 'process raft request' (duration: 286.908432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:10.70278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.900065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-27T21:50:10.702834Z","caller":"traceutil/trace.go:171","msg":"trace[2050287925] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25930; }","duration":"185.984658ms","start":"2026-04-27T21:50:10.516836Z","end":"2026-04-27T21:50:10.702821Z","steps":["trace[2050287925] 'agreement among raft nodes before linearized reading' (duration: 185.839024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:10.703108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.37385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-04-27T21:50:10.703186Z","caller":"traceutil/trace.go:171","msg":"trace[1720892035] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25930; }","duration":"220.430592ms","start":"2026-04-27T21:50:10.482707Z","end":"2026-04-27T21:50:10.703137Z","steps":["trace[1720892035] 'agreement among raft nodes before linearized reading' (duration: 220.300478ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:10.82464Z","caller":"traceutil/trace.go:171","msg":"trace[277173573] linearizableReadLoop","detail":"{readStateIndex:26787; appliedIndex:26786; }","duration":"116.707367ms","start":"2026-04-27T21:50:10.707899Z","end":"2026-04-27T21:50:10.824606Z","steps":["trace[277173573] 'read index received' (duration: 66.663546ms)","trace[277173573] 'applied index is now lower than readState.Index' (duration: 50.042671ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:10.824817Z","caller":"traceutil/trace.go:171","msg":"trace[487910903] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"138.572839ms","start":"2026-04-27T21:50:10.686204Z","end":"2026-04-27T21:50:10.824777Z","steps":["trace[487910903] 'process raft request' (duration: 88.24782ms)","trace[487910903] 'compare' (duration: 49.841776ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:50:10.825035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.112499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5557f4ed-b09c-4675-b1b4-1f799501fb4b\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-27T21:50:10.825212Z","caller":"traceutil/trace.go:171","msg":"trace[45689632] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5557f4ed-b09c-4675-b1b4-1f799501fb4b; range_end:; response_count:1; response_revision:25931; }","duration":"117.299284ms","start":"2026-04-27T21:50:10.707861Z","end":"2026-04-27T21:50:10.825161Z","steps":["trace[45689632] 'agreement among raft nodes before linearized reading' (duration: 116.973495ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:10.858834Z","caller":"traceutil/trace.go:171","msg":"trace[120024437] transaction","detail":"{read_only:false; response_revision:25932; number_of_response:1; }","duration":"145.031319ms","start":"2026-04-27T21:50:10.713757Z","end":"2026-04-27T21:50:10.858788Z","steps":["trace[120024437] 'process raft request' (duration: 144.68661ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:10.859261Z","caller":"traceutil/trace.go:171","msg":"trace[703743585] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"143.295831ms","start":"2026-04-27T21:50:10.715929Z","end":"2026-04-27T21:50:10.859225Z","steps":["trace[703743585] 'process raft request' (duration: 142.673544ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:28.967495Z","caller":"traceutil/trace.go:171","msg":"trace[139918185] transaction","detail":"{read_only:false; response_revision:26087; number_of_response:1; }","duration":"157.261343ms","start":"2026-04-27T21:50:28.810207Z","end":"2026-04-27T21:50:28.967468Z","steps":["trace[139918185] 'process raft request' (duration: 157.025336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:29.271838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.474984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:50:29.27196Z","caller":"traceutil/trace.go:171","msg":"trace[1178737945] linearizableReadLoop","detail":"{readStateIndex:26948; appliedIndex:26947; }","duration":"304.533466ms","start":"2026-04-27T21:50:28.967409Z","end":"2026-04-27T21:50:29.271943Z","steps":["trace[1178737945] 'read index received' (duration: 134.842166ms)","trace[1178737945] 'applied index is now lower than readState.Index' (duration: 169.69024ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:50:29.272147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.127214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-27T21:50:29.272181Z","caller":"traceutil/trace.go:171","msg":"trace[1873201270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26088; }","duration":"344.185215ms","start":"2026-04-27T21:50:28.927986Z","end":"2026-04-27T21:50:29.272172Z","steps":["trace[1873201270] 'agreement among raft nodes before linearized reading' (duration: 344.052562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:29.272212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:28.927972Z","time spent":"344.233037ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T21:50:29.272286Z","caller":"traceutil/trace.go:171","msg":"trace[204767834] transaction","detail":"{read_only:false; response_revision:26088; number_of_response:1; }","duration":"348.446844ms","start":"2026-04-27T21:50:28.923763Z","end":"2026-04-27T21:50:29.27221Z","steps":["trace[204767834] 'process raft request' (duration: 178.502457ms)","trace[204767834] 'compare' (duration: 168.661992ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:50:29.272325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.369479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-04-27T21:50:29.272466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.831906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-27T21:50:29.272561Z","caller":"traceutil/trace.go:171","msg":"trace[1232397523] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26088; }","duration":"323.961289ms","start":"2026-04-27T21:50:28.948581Z","end":"2026-04-27T21:50:29.272542Z","steps":["trace[1232397523] 'agreement among raft nodes before linearized reading' (duration: 323.66467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:29.272556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.639232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"warn","ts":"2026-04-27T21:50:29.272601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:28.948571Z","time spent":"324.02368ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T21:50:29.272326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.945079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10853"} {"level":"warn","ts":"2026-04-27T21:50:29.272621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:28.923742Z","time spent":"348.690842ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:29.272658Z","caller":"traceutil/trace.go:171","msg":"trace[908491818] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26088; }","duration":"262.800166ms","start":"2026-04-27T21:50:29.00983Z","end":"2026-04-27T21:50:29.27263Z","steps":["trace[908491818] 'agreement among raft nodes before linearized reading' (duration: 262.382484ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:29.272675Z","caller":"traceutil/trace.go:171","msg":"trace[209127287] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26088; }","duration":"359.370581ms","start":"2026-04-27T21:50:28.913288Z","end":"2026-04-27T21:50:29.272658Z","steps":["trace[209127287] 'agreement among raft nodes before linearized reading' (duration: 358.868857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:29.272719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:28.913269Z","time spent":"359.442102ms","remote":"127.0.0.1:39560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10875,"request content":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-04-27T21:50:29.272496Z","caller":"traceutil/trace.go:171","msg":"trace[265041668] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26088; }","duration":"325.591664ms","start":"2026-04-27T21:50:28.946874Z","end":"2026-04-27T21:50:29.272466Z","steps":["trace[265041668] 'agreement among raft nodes before linearized reading' (duration: 325.161273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:29.273784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:28.946853Z","time spent":"326.906162ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T21:50:29.597062Z","caller":"traceutil/trace.go:171","msg":"trace[1227033822] transaction","detail":"{read_only:false; response_revision:26089; number_of_response:1; }","duration":"320.343648ms","start":"2026-04-27T21:50:29.27668Z","end":"2026-04-27T21:50:29.597023Z","steps":["trace[1227033822] 'process raft request' (duration: 308.62958ms)","trace[1227033822] 'compare' (duration: 11.209444ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:50:29.597249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.276606Z","time spent":"320.559214ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:29.886459Z","caller":"traceutil/trace.go:171","msg":"trace[1399247461] transaction","detail":"{read_only:false; response_revision:26091; number_of_response:1; }","duration":"608.244779ms","start":"2026-04-27T21:50:29.278181Z","end":"2026-04-27T21:50:29.886426Z","steps":["trace[1399247461] 'process raft request' (duration: 608.157405ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:29.886458Z","caller":"traceutil/trace.go:171","msg":"trace[1819235162] transaction","detail":"{read_only:false; response_revision:26090; number_of_response:1; }","duration":"608.954278ms","start":"2026-04-27T21:50:29.277458Z","end":"2026-04-27T21:50:29.886412Z","steps":["trace[1819235162] 'process raft request' (duration: 608.729671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:29.886633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.27817Z","time spent":"608.378613ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:50:29.886669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.277433Z","time spent":"609.140664ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:29.88687Z","caller":"traceutil/trace.go:171","msg":"trace[1064359209] linearizableReadLoop","detail":"{readStateIndex:26952; appliedIndex:26948; }","duration":"524.425732ms","start":"2026-04-27T21:50:29.362426Z","end":"2026-04-27T21:50:29.886851Z","steps":["trace[1064359209] 'read index received' (duration: 222.988623ms)","trace[1064359209] 'applied index is now lower than readState.Index' (duration: 301.436069ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:29.886903Z","caller":"traceutil/trace.go:171","msg":"trace[1731352352] transaction","detail":"{read_only:false; response_revision:26092; number_of_response:1; }","duration":"600.45942ms","start":"2026-04-27T21:50:29.286425Z","end":"2026-04-27T21:50:29.886885Z","steps":["trace[1731352352] 'process raft request' (duration: 599.947625ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:29.886934Z","caller":"traceutil/trace.go:171","msg":"trace[514741409] transaction","detail":"{read_only:false; response_revision:26093; number_of_response:1; }","duration":"391.728167ms","start":"2026-04-27T21:50:29.495181Z","end":"2026-04-27T21:50:29.886909Z","steps":["trace[514741409] 'process raft request' (duration: 391.555682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:29.886971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.286415Z","time spent":"600.518333ms","remote":"127.0.0.1:39560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10655,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:50:29.887045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.617898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-27T21:50:29.887079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.495136Z","time spent":"391.873601ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:29.887091Z","caller":"traceutil/trace.go:171","msg":"trace[2028348659] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:26093; }","duration":"524.725841ms","start":"2026-04-27T21:50:29.362353Z","end":"2026-04-27T21:50:29.887079Z","steps":["trace[2028348659] 'agreement among raft nodes before linearized reading' (duration: 524.592567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:29.887126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.362335Z","time spent":"524.781683ms","remote":"127.0.0.1:46178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":4,"response size":30,"request content":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true "} {"level":"info","ts":"2026-04-27T21:50:30.202901Z","caller":"traceutil/trace.go:171","msg":"trace[1566131342] transaction","detail":"{read_only:false; response_revision:26095; number_of_response:1; }","duration":"339.733901ms","start":"2026-04-27T21:50:29.863137Z","end":"2026-04-27T21:50:30.202871Z","steps":["trace[1566131342] 'process raft request' (duration: 339.650518ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:30.202924Z","caller":"traceutil/trace.go:171","msg":"trace[1622236822] transaction","detail":"{read_only:false; response_revision:26094; number_of_response:1; }","duration":"584.159093ms","start":"2026-04-27T21:50:29.618728Z","end":"2026-04-27T21:50:30.202887Z","steps":["trace[1622236822] 'process raft request' (duration: 583.878315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.203065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.863112Z","time spent":"339.866896ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:50:30.203088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.618693Z","time spent":"584.315379ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:30.203279Z","caller":"traceutil/trace.go:171","msg":"trace[1683024283] transaction","detail":"{read_only:false; response_revision:26096; number_of_response:1; }","duration":"338.566179ms","start":"2026-04-27T21:50:29.864673Z","end":"2026-04-27T21:50:30.203239Z","steps":["trace[1683024283] 'process raft request' (duration: 338.160357ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:30.203413Z","caller":"traceutil/trace.go:171","msg":"trace[506718443] linearizableReadLoop","detail":"{readStateIndex:26959; appliedIndex:26953; }","duration":"316.4741ms","start":"2026-04-27T21:50:29.886919Z","end":"2026-04-27T21:50:30.203393Z","steps":["trace[506718443] 'read index received' (duration: 315.7801ms)","trace[506718443] 'applied index is now lower than readState.Index' (duration: 692.43µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:30.203398Z","caller":"traceutil/trace.go:171","msg":"trace[594795320] transaction","detail":"{read_only:false; response_revision:26097; number_of_response:1; }","duration":"335.348738ms","start":"2026-04-27T21:50:29.868016Z","end":"2026-04-27T21:50:30.203365Z","steps":["trace[594795320] 'process raft request' (duration: 335.105022ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:30.203501Z","caller":"traceutil/trace.go:171","msg":"trace[2039525630] transaction","detail":"{read_only:false; response_revision:26098; number_of_response:1; }","duration":"329.826484ms","start":"2026-04-27T21:50:29.873662Z","end":"2026-04-27T21:50:30.203489Z","steps":["trace[2039525630] 'process raft request' (duration: 329.616998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.203562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.312952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-27T21:50:30.203581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.87364Z","time spent":"329.904886ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:50:30.203549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.864663Z","time spent":"338.718633ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:50:30.203582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.867996Z","time spent":"335.499263ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:30.203626Z","caller":"traceutil/trace.go:171","msg":"trace[1613493806] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:26099; }","duration":"712.398835ms","start":"2026-04-27T21:50:29.491202Z","end":"2026-04-27T21:50:30.203601Z","steps":["trace[1613493806] 'agreement among raft nodes before linearized reading' (duration: 712.301072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.203633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.979018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:30.203667Z","caller":"traceutil/trace.go:171","msg":"trace[186987882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26099; }","duration":"525.05513ms","start":"2026-04-27T21:50:29.678603Z","end":"2026-04-27T21:50:30.203658Z","steps":["trace[186987882] 'agreement among raft nodes before linearized reading' (duration: 524.995228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.203663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.491188Z","time spent":"712.466086ms","remote":"127.0.0.1:38038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:50:30.203694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.678579Z","time spent":"525.105761ms","remote":"127.0.0.1:39374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T21:50:30.203688Z","caller":"traceutil/trace.go:171","msg":"trace[2022288601] transaction","detail":"{read_only:false; response_revision:26099; number_of_response:1; }","duration":"328.688292ms","start":"2026-04-27T21:50:29.874961Z","end":"2026-04-27T21:50:30.203649Z","steps":["trace[2022288601] 'process raft request' (duration: 328.374354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.203452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.390693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:30.2038Z","caller":"traceutil/trace.go:171","msg":"trace[2115376052] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26099; }","duration":"543.759203ms","start":"2026-04-27T21:50:29.660016Z","end":"2026-04-27T21:50:30.203775Z","steps":["trace[2115376052] 'agreement among raft nodes before linearized reading' (duration: 543.380502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.203846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.455392ms","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:8"} {"level":"warn","ts":"2026-04-27T21:50:30.203845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.660004Z","time spent":"543.824914ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-04-27T21:50:30.203877Z","caller":"traceutil/trace.go:171","msg":"trace[1656913074] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:26099; }","duration":"734.526923ms","start":"2026-04-27T21:50:29.469341Z","end":"2026-04-27T21:50:30.203868Z","steps":["trace[1656913074] 'agreement among raft nodes before linearized reading' (duration: 734.40803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.203864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.874955Z","time spent":"328.814666ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:50:30.203909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.469319Z","time spent":"734.582885ms","remote":"127.0.0.1:39566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":91,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:50:30.203731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.337808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:30.204042Z","caller":"traceutil/trace.go:171","msg":"trace[980062311] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26099; }","duration":"734.688098ms","start":"2026-04-27T21:50:29.469341Z","end":"2026-04-27T21:50:30.204029Z","steps":["trace[980062311] 'agreement among raft nodes before linearized reading' (duration: 734.364119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.204072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.469319Z","time spent":"734.74245ms","remote":"127.0.0.1:39612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-27T21:50:30.204162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.094475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-27T21:50:30.204207Z","caller":"traceutil/trace.go:171","msg":"trace[669630821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26099; }","duration":"812.162947ms","start":"2026-04-27T21:50:29.392032Z","end":"2026-04-27T21:50:30.204195Z","steps":["trace[669630821] 'agreement among raft nodes before linearized reading' (duration: 812.022324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.204237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.39202Z","time spent":"812.210398ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T21:50:30.204222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.566064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T21:50:30.20432Z","caller":"traceutil/trace.go:171","msg":"trace[1816307659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26099; }","duration":"809.697878ms","start":"2026-04-27T21:50:29.394598Z","end":"2026-04-27T21:50:30.204296Z","steps":["trace[1816307659] 'agreement among raft nodes before linearized reading' (duration: 809.422981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.204395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.394571Z","time spent":"809.811222ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T21:50:30.245403Z","caller":"traceutil/trace.go:171","msg":"trace[1466405958] transaction","detail":"{read_only:false; response_revision:26100; number_of_response:1; }","duration":"351.280704ms","start":"2026-04-27T21:50:29.894092Z","end":"2026-04-27T21:50:30.245373Z","steps":["trace[1466405958] 'process raft request' (duration: 350.913404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.245583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.894084Z","time spent":"351.398198ms","remote":"127.0.0.1:39548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:30.245653Z","caller":"traceutil/trace.go:171","msg":"trace[1331578430] transaction","detail":"{read_only:false; response_revision:26101; number_of_response:1; }","duration":"351.411908ms","start":"2026-04-27T21:50:29.894213Z","end":"2026-04-27T21:50:30.245625Z","steps":["trace[1331578430] 'process raft request' (duration: 351.024598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.245773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.894208Z","time spent":"351.4983ms","remote":"127.0.0.1:39690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:30.246123Z","caller":"traceutil/trace.go:171","msg":"trace[1921322925] transaction","detail":"{read_only:false; response_revision:26102; number_of_response:1; }","duration":"351.700397ms","start":"2026-04-27T21:50:29.894408Z","end":"2026-04-27T21:50:30.246108Z","steps":["trace[1921322925] 'process raft request' (duration: 351.08556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.246197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.894369Z","time spent":"351.787529ms","remote":"127.0.0.1:39690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:30.246351Z","caller":"traceutil/trace.go:171","msg":"trace[2023292940] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"345.742809ms","start":"2026-04-27T21:50:29.900581Z","end":"2026-04-27T21:50:30.246324Z","steps":["trace[2023292940] 'process raft request' (duration: 345.465431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:30.246476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:29.900555Z","time spent":"345.863232ms","remote":"127.0.0.1:39886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9632,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:50:30.246575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.334114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:30.246662Z","caller":"traceutil/trace.go:171","msg":"trace[1532672703] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26103; }","duration":"272.460207ms","start":"2026-04-27T21:50:29.97418Z","end":"2026-04-27T21:50:30.24664Z","steps":["trace[1532672703] 'agreement among raft nodes before linearized reading' (duration: 272.167819ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:34.544034Z","caller":"traceutil/trace.go:171","msg":"trace[1083539249] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"190.818412ms","start":"2026-04-27T21:50:34.353186Z","end":"2026-04-27T21:50:34.544005Z","steps":["trace[1083539249] 'process raft request' (duration: 190.620646ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:34.653678Z","caller":"traceutil/trace.go:171","msg":"trace[1615123247] linearizableReadLoop","detail":"{readStateIndex:27003; appliedIndex:27001; }","duration":"195.902584ms","start":"2026-04-27T21:50:34.457749Z","end":"2026-04-27T21:50:34.653652Z","steps":["trace[1615123247] 'read index received' (duration: 86.135022ms)","trace[1615123247] 'applied index is now lower than readState.Index' (duration: 109.766952ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:34.653839Z","caller":"traceutil/trace.go:171","msg":"trace[525310450] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"300.380619ms","start":"2026-04-27T21:50:34.353451Z","end":"2026-04-27T21:50:34.653832Z","steps":["trace[525310450] 'process raft request' (duration: 230.876825ms)","trace[525310450] 'compare' (duration: 69.243247ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:50:34.653926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:34.353437Z","time spent":"300.4224ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T21:50:34.654059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.308985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:34.654083Z","caller":"traceutil/trace.go:171","msg":"trace[821150566] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:26142; }","duration":"196.366317ms","start":"2026-04-27T21:50:34.457709Z","end":"2026-04-27T21:50:34.654076Z","steps":["trace[821150566] 'agreement among raft nodes before linearized reading' (duration: 196.313846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:34.654207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.712392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:34.654259Z","caller":"traceutil/trace.go:171","msg":"trace[90186274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26142; }","duration":"134.805264ms","start":"2026-04-27T21:50:34.519445Z","end":"2026-04-27T21:50:34.654251Z","steps":["trace[90186274] 'agreement among raft nodes before linearized reading' (duration: 134.731052ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:35.362508Z","caller":"traceutil/trace.go:171","msg":"trace[2025624348] transaction","detail":"{read_only:false; response_revision:26143; number_of_response:1; }","duration":"373.976409ms","start":"2026-04-27T21:50:34.988496Z","end":"2026-04-27T21:50:35.362472Z","steps":["trace[2025624348] 'process raft request' (duration: 373.726013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:35.3627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T21:50:34.988364Z","time spent":"374.243597ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T21:50:35.412333Z","caller":"traceutil/trace.go:171","msg":"trace[1651509738] transaction","detail":"{read_only:false; response_revision:26144; number_of_response:1; }","duration":"121.46291ms","start":"2026-04-27T21:50:35.290844Z","end":"2026-04-27T21:50:35.412307Z","steps":["trace[1651509738] 'process raft request' (duration: 121.269204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:35.778227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.487983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:35.778356Z","caller":"traceutil/trace.go:171","msg":"trace[1784748489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26146; }","duration":"100.661068ms","start":"2026-04-27T21:50:35.677656Z","end":"2026-04-27T21:50:35.778317Z","steps":["trace[1784748489] 'range keys from in-memory index tree' (duration: 100.416022ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:36.123057Z","caller":"traceutil/trace.go:171","msg":"trace[1130419740] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"191.616645ms","start":"2026-04-27T21:50:35.931412Z","end":"2026-04-27T21:50:36.123028Z","steps":["trace[1130419740] 'process raft request' (duration: 191.469101ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:36.35568Z","caller":"traceutil/trace.go:171","msg":"trace[1149418998] transaction","detail":"{read_only:false; response_revision:26151; number_of_response:1; }","duration":"127.061028ms","start":"2026-04-27T21:50:36.228593Z","end":"2026-04-27T21:50:36.355654Z","steps":["trace[1149418998] 'process raft request' (duration: 126.40646ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:36.355909Z","caller":"traceutil/trace.go:171","msg":"trace[1625658690] transaction","detail":"{read_only:false; response_revision:26152; number_of_response:1; }","duration":"127.253463ms","start":"2026-04-27T21:50:36.228645Z","end":"2026-04-27T21:50:36.355898Z","steps":["trace[1625658690] 'process raft request' (duration: 126.476452ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:36.35604Z","caller":"traceutil/trace.go:171","msg":"trace[334821708] transaction","detail":"{read_only:false; response_revision:26153; number_of_response:1; }","duration":"121.346767ms","start":"2026-04-27T21:50:36.234685Z","end":"2026-04-27T21:50:36.356032Z","steps":["trace[334821708] 'process raft request' (duration: 120.481503ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:36.356191Z","caller":"traceutil/trace.go:171","msg":"trace[1844113975] transaction","detail":"{read_only:false; response_revision:26154; number_of_response:1; }","duration":"121.45893ms","start":"2026-04-27T21:50:36.234723Z","end":"2026-04-27T21:50:36.356182Z","steps":["trace[1844113975] 'process raft request' (duration: 120.484253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:36.535384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.618374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T21:50:36.535785Z","caller":"traceutil/trace.go:171","msg":"trace[2147338289] transaction","detail":"{read_only:false; response_revision:26155; number_of_response:1; }","duration":"145.769351ms","start":"2026-04-27T21:50:36.389994Z","end":"2026-04-27T21:50:36.535763Z","steps":["trace[2147338289] 'process raft request' (duration: 18.642112ms)","trace[2147338289] 'compare' (duration: 126.10414ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:48.439014Z","caller":"traceutil/trace.go:171","msg":"trace[691067156] transaction","detail":"{read_only:false; response_revision:26237; number_of_response:1; }","duration":"101.387068ms","start":"2026-04-27T21:50:48.337597Z","end":"2026-04-27T21:50:48.438985Z","steps":["trace[691067156] 'process raft request' (duration: 75.254917ms)","trace[691067156] 'compare' (duration: 25.72613ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:53.612533Z","caller":"traceutil/trace.go:171","msg":"trace[2011188992] transaction","detail":"{read_only:false; response_revision:26273; number_of_response:1; }","duration":"159.112685ms","start":"2026-04-27T21:50:53.453392Z","end":"2026-04-27T21:50:53.612505Z","steps":["trace[2011188992] 'process raft request' (duration: 158.96204ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:53.613791Z","caller":"traceutil/trace.go:171","msg":"trace[552931773] transaction","detail":"{read_only:false; response_revision:26274; number_of_response:1; }","duration":"140.165504ms","start":"2026-04-27T21:50:53.473606Z","end":"2026-04-27T21:50:53.613771Z","steps":["trace[552931773] 'process raft request' (duration: 139.993509ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:53.613974Z","caller":"traceutil/trace.go:171","msg":"trace[457895913] linearizableReadLoop","detail":"{readStateIndex:27140; appliedIndex:27137; }","duration":"111.659625ms","start":"2026-04-27T21:50:53.50208Z","end":"2026-04-27T21:50:53.613739Z","steps":["trace[457895913] 'read index received' (duration: 110.279797ms)","trace[457895913] 'applied index is now lower than readState.Index' (duration: 1.379158ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:50:53.614107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.940532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:53.614216Z","caller":"traceutil/trace.go:171","msg":"trace[1164643857] transaction","detail":"{read_only:false; response_revision:26275; number_of_response:1; }","duration":"130.302139ms","start":"2026-04-27T21:50:53.483898Z","end":"2026-04-27T21:50:53.614201Z","steps":["trace[1164643857] 'process raft request' (duration: 129.795894ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:53.614237Z","caller":"traceutil/trace.go:171","msg":"trace[285398206] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26276; }","duration":"112.135449ms","start":"2026-04-27T21:50:53.502074Z","end":"2026-04-27T21:50:53.61421Z","steps":["trace[285398206] 'agreement among raft nodes before linearized reading' (duration: 111.84126ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:58.764151Z","caller":"traceutil/trace.go:171","msg":"trace[824795187] transaction","detail":"{read_only:false; response_revision:26328; number_of_response:1; }","duration":"145.258327ms","start":"2026-04-27T21:50:58.618848Z","end":"2026-04-27T21:50:58.764106Z","steps":["trace[824795187] 'process raft request' (duration: 145.067672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:59.232689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.556673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:59.232778Z","caller":"traceutil/trace.go:171","msg":"trace[158514704] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:26330; }","duration":"101.721347ms","start":"2026-04-27T21:50:59.131036Z","end":"2026-04-27T21:50:59.232757Z","steps":["trace[158514704] 'count revisions from in-memory index tree' (duration: 101.451771ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:59.781707Z","caller":"traceutil/trace.go:171","msg":"trace[446369175] transaction","detail":"{read_only:false; response_revision:26331; number_of_response:1; }","duration":"150.992628ms","start":"2026-04-27T21:50:59.63069Z","end":"2026-04-27T21:50:59.781683Z","steps":["trace[446369175] 'process raft request' (duration: 150.850954ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:59.830305Z","caller":"traceutil/trace.go:171","msg":"trace[363027] linearizableReadLoop","detail":"{readStateIndex:27198; appliedIndex:27197; }","duration":"151.330147ms","start":"2026-04-27T21:50:59.678878Z","end":"2026-04-27T21:50:59.830208Z","steps":["trace[363027] 'read index received' (duration: 102.84267ms)","trace[363027] 'applied index is now lower than readState.Index' (duration: 48.486177ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:59.830356Z","caller":"traceutil/trace.go:171","msg":"trace[769583955] transaction","detail":"{read_only:false; response_revision:26332; number_of_response:1; }","duration":"197.629993ms","start":"2026-04-27T21:50:59.632684Z","end":"2026-04-27T21:50:59.830314Z","steps":["trace[769583955] 'process raft request' (duration: 197.336295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:59.830415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.529522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:50:59.83046Z","caller":"traceutil/trace.go:171","msg":"trace[1232577529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26332; }","duration":"151.590605ms","start":"2026-04-27T21:50:59.67886Z","end":"2026-04-27T21:50:59.83045Z","steps":["trace[1232577529] 'agreement among raft nodes before linearized reading' (duration: 151.494091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:50:59.830646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.410955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-04-27T21:50:59.830701Z","caller":"traceutil/trace.go:171","msg":"trace[609227644] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:26332; }","duration":"123.512068ms","start":"2026-04-27T21:50:59.707174Z","end":"2026-04-27T21:50:59.830686Z","steps":["trace[609227644] 'agreement among raft nodes before linearized reading' (duration: 123.292252ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:50:59.994378Z","caller":"traceutil/trace.go:171","msg":"trace[1350158021] linearizableReadLoop","detail":"{readStateIndex:27199; appliedIndex:27198; }","duration":"156.259785ms","start":"2026-04-27T21:50:59.838076Z","end":"2026-04-27T21:50:59.994335Z","steps":["trace[1350158021] 'read index received' (duration: 61.285706ms)","trace[1350158021] 'applied index is now lower than readState.Index' (duration: 94.972269ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:50:59.994426Z","caller":"traceutil/trace.go:171","msg":"trace[1269702233] transaction","detail":"{read_only:false; response_revision:26333; number_of_response:1; }","duration":"159.30451ms","start":"2026-04-27T21:50:59.83507Z","end":"2026-04-27T21:50:59.994374Z","steps":["trace[1269702233] 'process raft request' (duration: 64.28109ms)","trace[1269702233] 'compare' (duration: 94.64172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T21:50:59.994683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.576834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-04-27T21:50:59.994773Z","caller":"traceutil/trace.go:171","msg":"trace[631757482] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:26333; }","duration":"156.688576ms","start":"2026-04-27T21:50:59.838046Z","end":"2026-04-27T21:50:59.994735Z","steps":["trace[631757482] 'agreement among raft nodes before linearized reading' (duration: 156.433239ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:00.227251Z","caller":"traceutil/trace.go:171","msg":"trace[1017414978] transaction","detail":"{read_only:false; response_revision:26335; number_of_response:1; }","duration":"146.47266ms","start":"2026-04-27T21:51:00.080728Z","end":"2026-04-27T21:51:00.227201Z","steps":["trace[1017414978] 'process raft request' (duration: 68.263461ms)","trace[1017414978] 'compare' (duration: 77.769397ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:00.471541Z","caller":"traceutil/trace.go:171","msg":"trace[1910844913] linearizableReadLoop","detail":"{readStateIndex:27202; appliedIndex:27201; }","duration":"138.072105ms","start":"2026-04-27T21:51:00.333441Z","end":"2026-04-27T21:51:00.471513Z","steps":["trace[1910844913] 'read index received' (duration: 137.90386ms)","trace[1910844913] 'applied index is now lower than readState.Index' (duration: 166.905µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:51:00.471664Z","caller":"traceutil/trace.go:171","msg":"trace[1780894542] transaction","detail":"{read_only:false; response_revision:26336; number_of_response:1; }","duration":"158.659541ms","start":"2026-04-27T21:51:00.312974Z","end":"2026-04-27T21:51:00.471633Z","steps":["trace[1780894542] 'process raft request' (duration: 158.362803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:00.471735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.270111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:00.471833Z","caller":"traceutil/trace.go:171","msg":"trace[1770570350] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26336; }","duration":"138.428665ms","start":"2026-04-27T21:51:00.333381Z","end":"2026-04-27T21:51:00.471809Z","steps":["trace[1770570350] 'agreement among raft nodes before linearized reading' (duration: 138.26112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T21:51:00.807812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.794966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-27T21:51:00.807915Z","caller":"traceutil/trace.go:171","msg":"trace[409164658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26341; }","duration":"127.953942ms","start":"2026-04-27T21:51:00.679937Z","end":"2026-04-27T21:51:00.807891Z","steps":["trace[409164658] 'range keys from in-memory index tree' (duration: 127.667434ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:51:12.007606Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006ab7.wal"} {"level":"info","ts":"2026-04-27T21:53:42.873755Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25044} {"level":"info","ts":"2026-04-27T21:53:43.013186Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25044,"took":"134.598828ms","hash":1632944958,"current-db-size-bytes":55709696,"current-db-size":"56 MB","current-db-size-in-use-bytes":36749312,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-27T21:53:43.013378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1632944958,"revision":25044,"compact-revision":21844} {"level":"info","ts":"2026-04-27T21:54:11.474581Z","caller":"traceutil/trace.go:171","msg":"trace[1880106213] transaction","detail":"{read_only:false; response_revision:28256; number_of_response:1; }","duration":"161.739169ms","start":"2026-04-27T21:54:11.312796Z","end":"2026-04-27T21:54:11.474536Z","steps":["trace[1880106213] 'process raft request' (duration: 115.069541ms)","trace[1880106213] 'compare' (duration: 46.186764ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T21:54:11.474713Z","caller":"traceutil/trace.go:171","msg":"trace[697194194] transaction","detail":"{read_only:false; response_revision:28257; number_of_response:1; }","duration":"159.6788ms","start":"2026-04-27T21:54:11.314993Z","end":"2026-04-27T21:54:11.474672Z","steps":["trace[697194194] 'process raft request' (duration: 159.265309ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:54:11.474787Z","caller":"traceutil/trace.go:171","msg":"trace[1459593992] transaction","detail":"{read_only:false; response_revision:28258; number_of_response:1; }","duration":"156.400599ms","start":"2026-04-27T21:54:11.318345Z","end":"2026-04-27T21:54:11.474745Z","steps":["trace[1459593992] 'process raft request' (duration: 155.980728ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:54:11.997473Z","caller":"traceutil/trace.go:171","msg":"trace[966339083] transaction","detail":"{read_only:false; response_revision:28260; number_of_response:1; }","duration":"105.73128ms","start":"2026-04-27T21:54:11.891709Z","end":"2026-04-27T21:54:11.99744Z","steps":["trace[966339083] 'process raft request' (duration: 105.544455ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T21:55:39.831117Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T21:55:39.836531Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-27T21:55:39.836609Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-27T21:58:42.880956Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27941} {"level":"info","ts":"2026-04-27T21:58:43.016664Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27941,"took":"130.950588ms","hash":4118979815,"current-db-size-bytes":55709696,"current-db-size":"56 MB","current-db-size-in-use-bytes":32116736,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-27T21:58:43.016733Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4118979815,"revision":27941,"compact-revision":25044} {"level":"info","ts":"2026-04-27T22:03:42.895457Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30405} {"level":"info","ts":"2026-04-27T22:03:43.047616Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30405,"took":"147.483208ms","hash":1051568632,"current-db-size-bytes":55709696,"current-db-size":"56 MB","current-db-size-in-use-bytes":30453760,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-27T22:03:43.047713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1051568632,"revision":30405,"compact-revision":27941} {"level":"info","ts":"2026-04-27T22:08:42.901616Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32721} {"level":"info","ts":"2026-04-27T22:08:43.06283Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32721,"took":"156.500129ms","hash":1596679569,"current-db-size-bytes":55709696,"current-db-size":"56 MB","current-db-size-in-use-bytes":31027200,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-27T22:08:43.062923Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1596679569,"revision":32721,"compact-revision":30405} {"level":"info","ts":"2026-04-27T22:13:42.910161Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35056} {"level":"info","ts":"2026-04-27T22:13:43.061043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35056,"took":"145.869228ms","hash":3961217045,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":31682560,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-27T22:13:43.061227Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3961217045,"revision":35056,"compact-revision":32721} {"level":"info","ts":"2026-04-27T22:16:56.264032Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-27T22:16:56.274037Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-27T22:16:56.274137Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-27T22:18:42.916427Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37287} {"level":"info","ts":"2026-04-27T22:18:43.032691Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37287,"took":"111.84506ms","hash":1467251114,"current-db-size-bytes":56598528,"current-db-size":"57 MB","current-db-size-in-use-bytes":31621120,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-27T22:18:43.03277Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1467251114,"revision":37287,"compact-revision":35056}