{"level":"warn","ts":"2026-04-08T12:54:17.535469Z","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-08T12:54:17.535613Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-08T12:54:17.535692Z","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-08T12:54:17.535707Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-08T12:54:17.535731Z","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-08T12:54:17.536771Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-04-08T12:54:17.536917Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-08T12:54:17.54437Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.181895ms"} {"level":"info","ts":"2026-04-08T12:54:17.554624Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-04-08T12:54:17.554733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T12:54:17.554765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-04-08T12:54:17.554789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T12:54:17.554807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-04-08T12:54:17.554858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-04-08T12:54:17.561414Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T12:54:17.562832Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T12:54:17.564041Z","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-08T12:54:17.567316Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T12:54:17.567538Z","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-08T12:54:17.567517Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T12:54:17.56759Z","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-08T12:54:17.567613Z","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-08T12:54:17.569043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-04-08T12:54:17.56974Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-08T12:54:17.572526Z","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-08T12:54:17.572822Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T12:54:17.572857Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T12:54:17.573027Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-08T12:54:17.573059Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-08T12:54:18.155785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T12:54:18.155843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T12:54:18.155866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-04-08T12:54:18.155885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-04-08T12:54:18.155897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-08T12:54:18.156049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-04-08T12:54:18.156089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-08T12:54:18.157266Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:54:18.158063Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T12:54:18.158056Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T12:54:18.158114Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T12:54:18.158544Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T12:54:18.158582Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T12:54:18.158855Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:54:18.158983Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:54:18.159024Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:54:18.161538Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-04-08T12:54:18.163349Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T12:54:22.458236Z","caller":"traceutil/trace.go:171","msg":"trace[786309349] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"737.768354ms","start":"2026-04-08T12:54:21.720442Z","end":"2026-04-08T12:54:22.458211Z","steps":["trace[786309349] 'process raft request' (duration: 737.61507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:22.458881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:54:21.720421Z","time spent":"737.906346ms","remote":"127.0.0.1:43210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:54:22.962838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495241267533111,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T12:54:22.971348Z","caller":"traceutil/trace.go:171","msg":"trace[792546141] linearizableReadLoop","detail":"{readStateIndex:258; appliedIndex:257; }","duration":"508.715927ms","start":"2026-04-08T12:54:22.462611Z","end":"2026-04-08T12:54:22.971327Z","steps":["trace[792546141] 'read index received' (duration: 505.826369ms)","trace[792546141] 'applied index is now lower than readState.Index' (duration: 2.888688ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:54:22.971423Z","caller":"traceutil/trace.go:171","msg":"trace[1740967756] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"1.14487575s","start":"2026-04-08T12:54:21.826516Z","end":"2026-04-08T12:54:22.971391Z","steps":["trace[1740967756] 'process raft request' (duration: 1.141942489s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:22.971546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:54:21.826486Z","time spent":"1.144991231s","remote":"127.0.0.1:43212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T12:54:22.971571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.898964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-d8a71y\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:22.97167Z","caller":"traceutil/trace.go:171","msg":"trace[105588868] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-d8a71y; range_end:; response_count:0; response_revision:252; }","duration":"509.078479ms","start":"2026-04-08T12:54:22.462579Z","end":"2026-04-08T12:54:22.971658Z","steps":["trace[105588868] 'agreement among raft nodes before linearized reading' (duration: 508.870871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:22.971755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:54:22.462564Z","time spent":"509.179969ms","remote":"127.0.0.1:43126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-d8a71y\" "} {"level":"warn","ts":"2026-04-08T12:54:22.971705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.138017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:22.972088Z","caller":"traceutil/trace.go:171","msg":"trace[924855460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:252; }","duration":"457.556364ms","start":"2026-04-08T12:54:22.514517Z","end":"2026-04-08T12:54:22.972073Z","steps":["trace[924855460] 'agreement among raft nodes before linearized reading' (duration: 457.017586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:22.972147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:54:22.514498Z","time spent":"457.637632ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T12:54:52.471548Z","caller":"traceutil/trace.go:171","msg":"trace[1472789052] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"237.201151ms","start":"2026-04-08T12:54:52.233666Z","end":"2026-04-08T12:54:52.470867Z","steps":["trace[1472789052] 'process raft request' (duration: 236.67148ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:54:55.402117Z","caller":"traceutil/trace.go:171","msg":"trace[1181634134] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"113.483353ms","start":"2026-04-08T12:54:55.288598Z","end":"2026-04-08T12:54:55.402081Z","steps":["trace[1181634134] 'read index received' (duration: 111.219274ms)","trace[1181634134] 'applied index is now lower than readState.Index' (duration: 2.263179ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:54:55.402552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.928876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-scxsz\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-08T12:54:55.402598Z","caller":"traceutil/trace.go:171","msg":"trace[723371928] range","detail":"{range_begin:/registry/pods/kube-system/cilium-scxsz; range_end:; response_count:1; response_revision:615; }","duration":"114.040848ms","start":"2026-04-08T12:54:55.288548Z","end":"2026-04-08T12:54:55.402589Z","steps":["trace[723371928] 'agreement among raft nodes before linearized reading' (duration: 113.918146ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:54:55.402397Z","caller":"traceutil/trace.go:171","msg":"trace[1150397590] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"457.456971ms","start":"2026-04-08T12:54:54.944902Z","end":"2026-04-08T12:54:55.402359Z","steps":["trace[1150397590] 'process raft request' (duration: 455.90001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:55.402934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:54:54.944863Z","time spent":"457.935798ms","remote":"127.0.0.1:43126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:54:55.901971Z","caller":"traceutil/trace.go:171","msg":"trace[1506148936] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"493.281154ms","start":"2026-04-08T12:54:55.408652Z","end":"2026-04-08T12:54:55.901933Z","steps":["trace[1506148936] 'process raft request' (duration: 488.937282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:55.902211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:54:55.408631Z","time spent":"493.485964ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:54:55.919496Z","caller":"traceutil/trace.go:171","msg":"trace[1351191702] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"496.659851ms","start":"2026-04-08T12:54:55.4228Z","end":"2026-04-08T12:54:55.91946Z","steps":["trace[1351191702] 'process raft request' (duration: 496.469803ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:54:55.919607Z","caller":"traceutil/trace.go:171","msg":"trace[2013279899] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:638; }","duration":"440.092376ms","start":"2026-04-08T12:54:55.479486Z","end":"2026-04-08T12:54:55.919579Z","steps":["trace[2013279899] 'read index received' (duration: 418.623664ms)","trace[2013279899] 'applied index is now lower than readState.Index' (duration: 21.467532ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:54:55.919734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:54:55.422769Z","time spent":"496.846999ms","remote":"127.0.0.1:43212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:54:55.919843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.339019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:55.919894Z","caller":"traceutil/trace.go:171","msg":"trace[793744167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"440.4456ms","start":"2026-04-08T12:54:55.479434Z","end":"2026-04-08T12:54:55.91988Z","steps":["trace[793744167] 'agreement among raft nodes before linearized reading' (duration: 440.249321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:55.919932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:54:55.479408Z","time spent":"440.514547ms","remote":"127.0.0.1:43042","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-08T12:54:55.919934Z","caller":"traceutil/trace.go:171","msg":"trace[1271603525] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"463.826787ms","start":"2026-04-08T12:54:55.456091Z","end":"2026-04-08T12:54:55.919918Z","steps":["trace[1271603525] 'process raft request' (duration: 463.303296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:55.920052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:54:55.456058Z","time spent":"463.914796ms","remote":"127.0.0.1:43332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T12:54:56.725371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.390911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:54:56.725511Z","caller":"traceutil/trace.go:171","msg":"trace[2015980939] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"795.139006ms","start":"2026-04-08T12:54:55.930331Z","end":"2026-04-08T12:54:56.72547Z","steps":["trace[2015980939] 'process raft request' (duration: 503.416043ms)","trace[2015980939] 'compare' (duration: 291.124175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:54:56.725596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:54:55.9303Z","time spent":"795.254027ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:54:56.737845Z","caller":"traceutil/trace.go:171","msg":"trace[1858734776] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"806.574717ms","start":"2026-04-08T12:54:55.931237Z","end":"2026-04-08T12:54:56.737811Z","steps":["trace[1858734776] 'process raft request' (duration: 806.416402ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:54:56.73792Z","caller":"traceutil/trace.go:171","msg":"trace[1457806857] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"807.125381ms","start":"2026-04-08T12:54:55.930744Z","end":"2026-04-08T12:54:56.737869Z","steps":["trace[1457806857] 'process raft request' (duration: 806.983767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:56.738061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:54:55.931218Z","time spent":"806.724022ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:54:56.73826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:54:55.930729Z","time spent":"807.378015ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:54:56.743874Z","caller":"traceutil/trace.go:171","msg":"trace[1862396441] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:642; }","duration":"262.661801ms","start":"2026-04-08T12:54:56.481179Z","end":"2026-04-08T12:54:56.74384Z","steps":["trace[1862396441] 'read index received' (duration: 256.391393ms)","trace[1862396441] 'applied index is now lower than readState.Index' (duration: 6.269568ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:54:56.743899Z","caller":"traceutil/trace.go:171","msg":"trace[1689393161] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"421.970811ms","start":"2026-04-08T12:54:56.321896Z","end":"2026-04-08T12:54:56.743867Z","steps":["trace[1689393161] 'process raft request' (duration: 421.457011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:56.744027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.833039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:56.744104Z","caller":"traceutil/trace.go:171","msg":"trace[311154458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"262.961132ms","start":"2026-04-08T12:54:56.48112Z","end":"2026-04-08T12:54:56.744081Z","steps":["trace[311154458] 'agreement among raft nodes before linearized reading' (duration: 262.823967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:56.744137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:54:56.321872Z","time spent":"422.097483ms","remote":"127.0.0.1:43126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:54:57.673258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.887869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:57.673342Z","caller":"traceutil/trace.go:171","msg":"trace[1954314657] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:634; }","duration":"130.063896ms","start":"2026-04-08T12:54:57.54326Z","end":"2026-04-08T12:54:57.673324Z","steps":["trace[1954314657] 'count revisions from in-memory index tree' (duration: 129.774618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:57.673382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.903854ms","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-08T12:54:57.67335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.4388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T12:54:57.673535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.246457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:57.67358Z","caller":"traceutil/trace.go:171","msg":"trace[268736303] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:634; }","duration":"154.321944ms","start":"2026-04-08T12:54:57.519238Z","end":"2026-04-08T12:54:57.67356Z","steps":["trace[268736303] 'range keys from in-memory index tree' (duration: 154.184151ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:54:57.67349Z","caller":"traceutil/trace.go:171","msg":"trace[1442805819] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:634; }","duration":"154.052238ms","start":"2026-04-08T12:54:57.519415Z","end":"2026-04-08T12:54:57.673467Z","steps":["trace[1442805819] 'count revisions from in-memory index tree' (duration: 153.869301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:57.673637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.301734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:57.673707Z","caller":"traceutil/trace.go:171","msg":"trace[2092829582] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:634; }","duration":"110.391083ms","start":"2026-04-08T12:54:57.563297Z","end":"2026-04-08T12:54:57.673688Z","steps":["trace[2092829582] 'range keys from in-memory index tree' (duration: 110.26199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:57.673727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.819786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:57.673795Z","caller":"traceutil/trace.go:171","msg":"trace[424451943] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:634; }","duration":"153.85655ms","start":"2026-04-08T12:54:57.519893Z","end":"2026-04-08T12:54:57.673749Z","steps":["trace[424451943] 'range keys from in-memory index tree' (duration: 153.792274ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:54:57.673553Z","caller":"traceutil/trace.go:171","msg":"trace[241264748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"192.681504ms","start":"2026-04-08T12:54:57.480841Z","end":"2026-04-08T12:54:57.673523Z","steps":["trace[241264748] 'range keys from in-memory index tree' (duration: 192.376854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:57.673873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.126409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:57.673938Z","caller":"traceutil/trace.go:171","msg":"trace[2036930999] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:634; }","duration":"196.224459ms","start":"2026-04-08T12:54:57.477675Z","end":"2026-04-08T12:54:57.673899Z","steps":["trace[2036930999] 'range keys from in-memory index tree' (duration: 196.015779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:57.673964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.041103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T12:54:57.673978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.047671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:57.674012Z","caller":"traceutil/trace.go:171","msg":"trace[2080657504] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:634; }","duration":"130.119241ms","start":"2026-04-08T12:54:57.54388Z","end":"2026-04-08T12:54:57.673999Z","steps":["trace[2080657504] 'range keys from in-memory index tree' (duration: 129.983157ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:54:57.674031Z","caller":"traceutil/trace.go:171","msg":"trace[608955333] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:634; }","duration":"196.112478ms","start":"2026-04-08T12:54:57.477904Z","end":"2026-04-08T12:54:57.674017Z","steps":["trace[608955333] 'count revisions from in-memory index tree' (duration: 195.984236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:57.674155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.327794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:57.674192Z","caller":"traceutil/trace.go:171","msg":"trace[768381221] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:634; }","duration":"111.38168ms","start":"2026-04-08T12:54:57.5628Z","end":"2026-04-08T12:54:57.674182Z","steps":["trace[768381221] 'range keys from in-memory index tree' (duration: 111.277608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:57.674355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.303012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T12:54:57.674377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.108568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:57.674394Z","caller":"traceutil/trace.go:171","msg":"trace[1354222027] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:634; }","duration":"111.367538ms","start":"2026-04-08T12:54:57.563017Z","end":"2026-04-08T12:54:57.674384Z","steps":["trace[1354222027] 'count revisions from in-memory index tree' (duration: 111.271259ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:54:57.674424Z","caller":"traceutil/trace.go:171","msg":"trace[1216526513] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:634; }","duration":"196.228069ms","start":"2026-04-08T12:54:57.478183Z","end":"2026-04-08T12:54:57.674411Z","steps":["trace[1216526513] 'range keys from in-memory index tree' (duration: 196.03769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:57.674476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.34308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:57.674523Z","caller":"traceutil/trace.go:171","msg":"trace[340652457] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:634; }","duration":"131.415827ms","start":"2026-04-08T12:54:57.543095Z","end":"2026-04-08T12:54:57.674511Z","steps":["trace[340652457] 'range keys from in-memory index tree' (duration: 131.284355ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:54:58.480203Z","caller":"traceutil/trace.go:171","msg":"trace[787772725] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"127.719034ms","start":"2026-04-08T12:54:58.352458Z","end":"2026-04-08T12:54:58.480177Z","steps":["trace[787772725] 'process raft request' (duration: 126.723557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:58.481046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.967624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:58.481152Z","caller":"traceutil/trace.go:171","msg":"trace[194006195] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:640; }","duration":"118.138302ms","start":"2026-04-08T12:54:58.362994Z","end":"2026-04-08T12:54:58.481132Z","steps":["trace[194006195] 'agreement among raft nodes before linearized reading' (duration: 117.380138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:58.481391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.126529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-08T12:54:58.481446Z","caller":"traceutil/trace.go:171","msg":"trace[829889607] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:640; }","duration":"111.250951ms","start":"2026-04-08T12:54:58.370181Z","end":"2026-04-08T12:54:58.481432Z","steps":["trace[829889607] 'agreement among raft nodes before linearized reading' (duration: 110.018861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:54:58.48224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.081263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T12:54:58.482375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.291704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:58.482496Z","caller":"traceutil/trace.go:171","msg":"trace[1863437841] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:640; }","duration":"119.447119ms","start":"2026-04-08T12:54:58.363031Z","end":"2026-04-08T12:54:58.482478Z","steps":["trace[1863437841] 'agreement among raft nodes before linearized reading' (duration: 117.328212ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:54:58.482559Z","caller":"traceutil/trace.go:171","msg":"trace[555776088] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:640; }","duration":"119.290613ms","start":"2026-04-08T12:54:58.363103Z","end":"2026-04-08T12:54:58.482394Z","steps":["trace[555776088] 'agreement among raft nodes before linearized reading' (duration: 117.266426ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:54:59.516687Z","caller":"traceutil/trace.go:171","msg":"trace[963920505] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"130.515641ms","start":"2026-04-08T12:54:59.386122Z","end":"2026-04-08T12:54:59.516638Z","steps":["trace[963920505] 'process raft request' (duration: 112.677803ms)","trace[963920505] 'compare' (duration: 17.659861ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:54:59.824738Z","caller":"traceutil/trace.go:171","msg":"trace[1033766797] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"103.275587ms","start":"2026-04-08T12:54:59.721436Z","end":"2026-04-08T12:54:59.824712Z","steps":["trace[1033766797] 'read index received' (duration: 83.778896ms)","trace[1033766797] 'applied index is now lower than readState.Index' (duration: 19.495461ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:54:59.825006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.534872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:54:59.825078Z","caller":"traceutil/trace.go:171","msg":"trace[71907971] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:677; }","duration":"103.634042ms","start":"2026-04-08T12:54:59.721429Z","end":"2026-04-08T12:54:59.825063Z","steps":["trace[71907971] 'agreement among raft nodes before linearized reading' (duration: 103.385158ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:54:59.825049Z","caller":"traceutil/trace.go:171","msg":"trace[300548111] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"122.917692ms","start":"2026-04-08T12:54:59.702078Z","end":"2026-04-08T12:54:59.824996Z","steps":["trace[300548111] 'process raft request' (duration: 103.141764ms)","trace[300548111] 'compare' (duration: 19.366828ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:55:12.343913Z","caller":"traceutil/trace.go:171","msg":"trace[1824815557] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"345.676172ms","start":"2026-04-08T12:55:11.998194Z","end":"2026-04-08T12:55:12.34387Z","steps":["trace[1824815557] 'process raft request' (duration: 345.433975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:12.344356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:11.998159Z","time spent":"345.866141ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:55:18.146038Z","caller":"traceutil/trace.go:171","msg":"trace[1901826570] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"120.799589ms","start":"2026-04-08T12:55:18.025213Z","end":"2026-04-08T12:55:18.146012Z","steps":["trace[1901826570] 'process raft request' (duration: 120.678974ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:18.624818Z","caller":"traceutil/trace.go:171","msg":"trace[1155761532] linearizableReadLoop","detail":"{readStateIndex:872; appliedIndex:869; }","duration":"145.04349ms","start":"2026-04-08T12:55:18.479754Z","end":"2026-04-08T12:55:18.624798Z","steps":["trace[1155761532] 'read index received' (duration: 42.725088ms)","trace[1155761532] 'applied index is now lower than readState.Index' (duration: 102.317552ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:55:18.624833Z","caller":"traceutil/trace.go:171","msg":"trace[1651602850] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"148.427944ms","start":"2026-04-08T12:55:18.47637Z","end":"2026-04-08T12:55:18.624798Z","steps":["trace[1651602850] 'process raft request' (duration: 147.851518ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:18.624929Z","caller":"traceutil/trace.go:171","msg":"trace[696547659] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"145.681082ms","start":"2026-04-08T12:55:18.479192Z","end":"2026-04-08T12:55:18.624873Z","steps":["trace[696547659] 'process raft request' (duration: 145.518079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:18.624954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.180744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:55:18.624998Z","caller":"traceutil/trace.go:171","msg":"trace[1898112698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:843; }","duration":"145.249171ms","start":"2026-04-08T12:55:18.479737Z","end":"2026-04-08T12:55:18.624986Z","steps":["trace[1898112698] 'agreement among raft nodes before linearized reading' (duration: 145.151715ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:20.219889Z","caller":"traceutil/trace.go:171","msg":"trace[2111704085] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"109.521874ms","start":"2026-04-08T12:55:20.110339Z","end":"2026-04-08T12:55:20.219861Z","steps":["trace[2111704085] 'process raft request' (duration: 109.340811ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:20.35837Z","caller":"traceutil/trace.go:171","msg":"trace[1107464891] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"155.756079ms","start":"2026-04-08T12:55:20.202597Z","end":"2026-04-08T12:55:20.358353Z","steps":["trace[1107464891] 'process raft request' (duration: 155.647953ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:20.358362Z","caller":"traceutil/trace.go:171","msg":"trace[1666915411] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"157.487162ms","start":"2026-04-08T12:55:20.200846Z","end":"2026-04-08T12:55:20.358334Z","steps":["trace[1666915411] 'process raft request' (duration: 156.922304ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:20.358649Z","caller":"traceutil/trace.go:171","msg":"trace[876229702] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"155.766269ms","start":"2026-04-08T12:55:20.202859Z","end":"2026-04-08T12:55:20.358626Z","steps":["trace[876229702] 'process raft request' (duration: 155.435201ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:26.698958Z","caller":"traceutil/trace.go:171","msg":"trace[888511210] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"138.630682ms","start":"2026-04-08T12:55:26.560311Z","end":"2026-04-08T12:55:26.698941Z","steps":["trace[888511210] 'process raft request' (duration: 138.514525ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:43.251374Z","caller":"traceutil/trace.go:171","msg":"trace[642510387] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1219; }","duration":"289.156984ms","start":"2026-04-08T12:55:42.962196Z","end":"2026-04-08T12:55:43.251353Z","steps":["trace[642510387] 'read index received' (duration: 288.965304ms)","trace[642510387] 'applied index is now lower than readState.Index' (duration: 190.89µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:55:43.251481Z","caller":"traceutil/trace.go:171","msg":"trace[2051994170] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"467.821812ms","start":"2026-04-08T12:55:42.783634Z","end":"2026-04-08T12:55:43.251456Z","steps":["trace[2051994170] 'process raft request' (duration: 467.589282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:43.251631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.417163ms","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-08T12:55:43.251705Z","caller":"traceutil/trace.go:171","msg":"trace[291481269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1184; }","duration":"289.541093ms","start":"2026-04-08T12:55:42.962145Z","end":"2026-04-08T12:55:43.251686Z","steps":["trace[291481269] 'agreement among raft nodes before linearized reading' (duration: 289.327923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:43.251711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:42.783618Z","time spent":"468.015971ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:55:43.251629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.267713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-08T12:55:43.251728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.65493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-08T12:55:43.2518Z","caller":"traceutil/trace.go:171","msg":"trace[1031474907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1184; }","duration":"127.522253ms","start":"2026-04-08T12:55:43.124253Z","end":"2026-04-08T12:55:43.251775Z","steps":["trace[1031474907] 'agreement among raft nodes before linearized reading' (duration: 127.244103ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:43.251833Z","caller":"traceutil/trace.go:171","msg":"trace[1176103305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1184; }","duration":"108.77895ms","start":"2026-04-08T12:55:43.143032Z","end":"2026-04-08T12:55:43.251811Z","steps":["trace[1176103305] 'agreement among raft nodes before linearized reading' (duration: 108.56965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:43.252042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.637002ms","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-08T12:55:43.252194Z","caller":"traceutil/trace.go:171","msg":"trace[1131924714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1184; }","duration":"111.864682ms","start":"2026-04-08T12:55:43.140302Z","end":"2026-04-08T12:55:43.252167Z","steps":["trace[1131924714] 'agreement among raft nodes before linearized reading' (duration: 111.387363ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:57.63152Z","caller":"traceutil/trace.go:171","msg":"trace[1180317827] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"250.576977ms","start":"2026-04-08T12:55:57.380918Z","end":"2026-04-08T12:55:57.631495Z","steps":["trace[1180317827] 'read index received' (duration: 244.239258ms)","trace[1180317827] 'applied index is now lower than readState.Index' (duration: 6.336899ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:55:57.631546Z","caller":"traceutil/trace.go:171","msg":"trace[460881041] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"253.320205ms","start":"2026-04-08T12:55:57.378204Z","end":"2026-04-08T12:55:57.631524Z","steps":["trace[460881041] 'process raft request' (duration: 247.015207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:57.631705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.779579ms","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-08T12:55:57.631735Z","caller":"traceutil/trace.go:171","msg":"trace[1784267357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1318; }","duration":"250.83934ms","start":"2026-04-08T12:55:57.380889Z","end":"2026-04-08T12:55:57.631728Z","steps":["trace[1784267357] 'agreement among raft nodes before linearized reading' (duration: 250.732438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:57.631837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.12799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-08T12:55:57.631862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.053571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:55:57.631887Z","caller":"traceutil/trace.go:171","msg":"trace[1116613715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1318; }","duration":"250.203911ms","start":"2026-04-08T12:55:57.381669Z","end":"2026-04-08T12:55:57.631873Z","steps":["trace[1116613715] 'agreement among raft nodes before linearized reading' (duration: 250.035408ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:57.631895Z","caller":"traceutil/trace.go:171","msg":"trace[123396695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1318; }","duration":"150.116192ms","start":"2026-04-08T12:55:57.481768Z","end":"2026-04-08T12:55:57.631884Z","steps":["trace[123396695] 'agreement among raft nodes before linearized reading' (duration: 150.063051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:02.516961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.503931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T12:56:02.517114Z","caller":"traceutil/trace.go:171","msg":"trace[1104407106] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1415; }","duration":"152.656664ms","start":"2026-04-08T12:56:02.364427Z","end":"2026-04-08T12:56:02.517084Z","steps":["trace[1104407106] 'count revisions from in-memory index tree' (duration: 152.44845ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:05.953528Z","caller":"traceutil/trace.go:171","msg":"trace[503159685] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"254.091656ms","start":"2026-04-08T12:56:05.6994Z","end":"2026-04-08T12:56:05.953491Z","steps":["trace[503159685] 'process raft request' (duration: 169.391571ms)","trace[503159685] 'compare' (duration: 84.503311ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:56:05.953647Z","caller":"traceutil/trace.go:171","msg":"trace[1664612705] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"253.942023ms","start":"2026-04-08T12:56:05.699684Z","end":"2026-04-08T12:56:05.953626Z","steps":["trace[1664612705] 'process raft request' (duration: 253.769509ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:22.76707Z","caller":"traceutil/trace.go:171","msg":"trace[157328678] linearizableReadLoop","detail":"{readStateIndex:1816; appliedIndex:1815; }","duration":"141.004966ms","start":"2026-04-08T12:56:22.626043Z","end":"2026-04-08T12:56:22.767048Z","steps":["trace[157328678] 'read index received' (duration: 140.81612ms)","trace[157328678] 'applied index is now lower than readState.Index' (duration: 188.026µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:56:22.767294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.017797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T12:56:22.76728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.209304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:56:22.767326Z","caller":"traceutil/trace.go:171","msg":"trace[1946528214] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"150.965354ms","start":"2026-04-08T12:56:22.616331Z","end":"2026-04-08T12:56:22.767296Z","steps":["trace[1946528214] 'process raft request' (duration: 150.588371ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:22.767352Z","caller":"traceutil/trace.go:171","msg":"trace[915264591] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1746; }","duration":"141.09271ms","start":"2026-04-08T12:56:22.626246Z","end":"2026-04-08T12:56:22.767339Z","steps":["trace[915264591] 'agreement among raft nodes before linearized reading' (duration: 141.009637ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:22.767363Z","caller":"traceutil/trace.go:171","msg":"trace[479792882] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1746; }","duration":"141.328108ms","start":"2026-04-08T12:56:22.62602Z","end":"2026-04-08T12:56:22.767348Z","steps":["trace[479792882] 'agreement among raft nodes before linearized reading' (duration: 141.168602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:22.767498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.777274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T12:56:22.767529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.834416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:56:22.767548Z","caller":"traceutil/trace.go:171","msg":"trace[1945186163] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1746; }","duration":"114.867817ms","start":"2026-04-08T12:56:22.652674Z","end":"2026-04-08T12:56:22.767542Z","steps":["trace[1945186163] 'agreement among raft nodes before linearized reading' (duration: 114.838306ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:22.767543Z","caller":"traceutil/trace.go:171","msg":"trace[1911844625] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1746; }","duration":"114.832916ms","start":"2026-04-08T12:56:22.652701Z","end":"2026-04-08T12:56:22.767534Z","steps":["trace[1911844625] 'agreement among raft nodes before linearized reading' (duration: 114.766343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:22.767683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.454509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:56:22.767715Z","caller":"traceutil/trace.go:171","msg":"trace[1399687146] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1746; }","duration":"105.519002ms","start":"2026-04-08T12:56:22.662188Z","end":"2026-04-08T12:56:22.767707Z","steps":["trace[1399687146] 'agreement among raft nodes before linearized reading' (duration: 105.443079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:22.767731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.917734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T12:56:22.767715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.567533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:56:22.767756Z","caller":"traceutil/trace.go:171","msg":"trace[17041806] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1746; }","duration":"125.955235ms","start":"2026-04-08T12:56:22.641795Z","end":"2026-04-08T12:56:22.76775Z","steps":["trace[17041806] 'agreement among raft nodes before linearized reading' (duration: 125.915384ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:22.767766Z","caller":"traceutil/trace.go:171","msg":"trace[426763910] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1746; }","duration":"105.632176ms","start":"2026-04-08T12:56:22.662125Z","end":"2026-04-08T12:56:22.767757Z","steps":["trace[426763910] 'agreement among raft nodes before linearized reading' (duration: 105.564583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:22.767858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.055089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:56:22.767875Z","caller":"traceutil/trace.go:171","msg":"trace[1628008766] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1746; }","duration":"126.08697ms","start":"2026-04-08T12:56:22.641783Z","end":"2026-04-08T12:56:22.76787Z","steps":["trace[1628008766] 'agreement among raft nodes before linearized reading' (duration: 126.059669ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:32.117075Z","caller":"traceutil/trace.go:171","msg":"trace[396536285] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"184.597936ms","start":"2026-04-08T12:56:31.932442Z","end":"2026-04-08T12:56:32.11704Z","steps":["trace[396536285] 'read index received' (duration: 184.299174ms)","trace[396536285] 'applied index is now lower than readState.Index' (duration: 297.342µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:56:32.117228Z","caller":"traceutil/trace.go:171","msg":"trace[1125192779] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"261.383935ms","start":"2026-04-08T12:56:31.855784Z","end":"2026-04-08T12:56:32.117168Z","steps":["trace[1125192779] 'process raft request' (duration: 261.062651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:32.117438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.963702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-604ce05a-4166-4a99-977d-f1922d032315\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-08T12:56:32.117486Z","caller":"traceutil/trace.go:171","msg":"trace[1607372539] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-604ce05a-4166-4a99-977d-f1922d032315; range_end:; response_count:1; response_revision:1867; }","duration":"185.083318ms","start":"2026-04-08T12:56:31.932388Z","end":"2026-04-08T12:56:32.117472Z","steps":["trace[1607372539] 'agreement among raft nodes before linearized reading' (duration: 184.874318ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:32.373255Z","caller":"traceutil/trace.go:171","msg":"trace[2006542311] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"251.138981ms","start":"2026-04-08T12:56:32.122082Z","end":"2026-04-08T12:56:32.373221Z","steps":["trace[2006542311] 'process raft request' (duration: 250.841719ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:32.38172Z","caller":"traceutil/trace.go:171","msg":"trace[65045793] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1939; }","duration":"234.145308ms","start":"2026-04-08T12:56:32.147503Z","end":"2026-04-08T12:56:32.381648Z","steps":["trace[65045793] 'read index received' (duration: 225.551363ms)","trace[65045793] 'applied index is now lower than readState.Index' (duration: 8.592735ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:56:32.381857Z","caller":"traceutil/trace.go:171","msg":"trace[960307687] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"258.87966ms","start":"2026-04-08T12:56:32.122939Z","end":"2026-04-08T12:56:32.381819Z","steps":["trace[960307687] 'process raft request' (duration: 258.549297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:32.382048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.521294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T12:56:32.382101Z","caller":"traceutil/trace.go:171","msg":"trace[1060353363] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1869; }","duration":"234.622768ms","start":"2026-04-08T12:56:32.147463Z","end":"2026-04-08T12:56:32.382085Z","steps":["trace[1060353363] 'agreement among raft nodes before linearized reading' (duration: 234.354806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:32.623362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.241026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-08T12:56:32.623475Z","caller":"traceutil/trace.go:171","msg":"trace[577416630] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1869; }","duration":"177.460236ms","start":"2026-04-08T12:56:32.445982Z","end":"2026-04-08T12:56:32.623443Z","steps":["trace[577416630] 'range keys from in-memory index tree' (duration: 176.892132ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:36.538576Z","caller":"traceutil/trace.go:171","msg":"trace[1457890761] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"405.239824ms","start":"2026-04-08T12:56:36.13331Z","end":"2026-04-08T12:56:36.53855Z","steps":["trace[1457890761] 'read index received' (duration: 405.043105ms)","trace[1457890761] 'applied index is now lower than readState.Index' (duration: 195.219µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:56:36.538725Z","caller":"traceutil/trace.go:171","msg":"trace[1108516798] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"716.869567ms","start":"2026-04-08T12:56:35.821812Z","end":"2026-04-08T12:56:36.538681Z","steps":["trace[1108516798] 'process raft request' (duration: 716.564733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:36.5389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.570208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-08T12:56:36.538946Z","caller":"traceutil/trace.go:171","msg":"trace[2078541485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1891; }","duration":"405.677053ms","start":"2026-04-08T12:56:36.133258Z","end":"2026-04-08T12:56:36.538935Z","steps":["trace[2078541485] 'agreement among raft nodes before linearized reading' (duration: 405.414031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:36.538938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:56:35.821761Z","time spent":"717.065665ms","remote":"127.0.0.1:43212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3902,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:56:36.538987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:56:36.133235Z","time spent":"405.742436ms","remote":"127.0.0.1:43282","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-08T12:56:36.542631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.166698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-604ce05a-4166-4a99-977d-f1922d032315\" ","response":"range_response_count:1 size:3917"} {"level":"info","ts":"2026-04-08T12:56:36.542748Z","caller":"traceutil/trace.go:171","msg":"trace[395481066] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-604ce05a-4166-4a99-977d-f1922d032315; range_end:; response_count:1; response_revision:1891; }","duration":"370.333566ms","start":"2026-04-08T12:56:36.172376Z","end":"2026-04-08T12:56:36.54271Z","steps":["trace[395481066] 'agreement among raft nodes before linearized reading' (duration: 370.113966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:36.542809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:56:36.172351Z","time spent":"370.444311ms","remote":"127.0.0.1:43212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3940,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-604ce05a-4166-4a99-977d-f1922d032315\" "} {"level":"warn","ts":"2026-04-08T12:56:36.542994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.443049ms","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-08T12:56:36.543063Z","caller":"traceutil/trace.go:171","msg":"trace[2041558888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1891; }","duration":"151.517352ms","start":"2026-04-08T12:56:36.391531Z","end":"2026-04-08T12:56:36.543048Z","steps":["trace[2041558888] 'agreement among raft nodes before linearized reading' (duration: 151.286322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:36.543098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.720662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T12:56:36.543446Z","caller":"traceutil/trace.go:171","msg":"trace[2008536472] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1891; }","duration":"136.088449ms","start":"2026-04-08T12:56:36.407331Z","end":"2026-04-08T12:56:36.543419Z","steps":["trace[2008536472] 'agreement among raft nodes before linearized reading' (duration: 135.425599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:36.5431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.434217ms","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-08T12:56:36.543647Z","caller":"traceutil/trace.go:171","msg":"trace[1058873030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1891; }","duration":"157.981572ms","start":"2026-04-08T12:56:36.385627Z","end":"2026-04-08T12:56:36.543609Z","steps":["trace[1058873030] 'agreement among raft nodes before linearized reading' (duration: 157.207468ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:38.688709Z","caller":"traceutil/trace.go:171","msg":"trace[1587634022] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"100.879662ms","start":"2026-04-08T12:56:38.587776Z","end":"2026-04-08T12:56:38.688656Z","steps":["trace[1587634022] 'process raft request' (duration: 97.448274ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:46.499109Z","caller":"traceutil/trace.go:171","msg":"trace[1218169027] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"170.678775ms","start":"2026-04-08T12:56:46.328405Z","end":"2026-04-08T12:56:46.499084Z","steps":["trace[1218169027] 'process raft request' (duration: 170.452144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:46.727462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.394519ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T12:56:46.727758Z","caller":"traceutil/trace.go:171","msg":"trace[1350677319] linearizableReadLoop","detail":"{readStateIndex:2062; appliedIndex:2060; }","duration":"249.68746ms","start":"2026-04-08T12:56:46.478027Z","end":"2026-04-08T12:56:46.727714Z","steps":["trace[1350677319] 'read index received' (duration: 20.93942ms)","trace[1350677319] 'applied index is now lower than readState.Index' (duration: 228.7439ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:56:46.728255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.158851ms","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-08T12:56:46.729297Z","caller":"traceutil/trace.go:171","msg":"trace[44704547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1988; }","duration":"122.286598ms","start":"2026-04-08T12:56:46.60604Z","end":"2026-04-08T12:56:46.728327Z","steps":["trace[44704547] 'agreement among raft nodes before linearized reading' (duration: 122.072497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:46.729502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.832822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T12:56:46.72957Z","caller":"traceutil/trace.go:171","msg":"trace[1772646388] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1988; }","duration":"110.942567ms","start":"2026-04-08T12:56:46.618611Z","end":"2026-04-08T12:56:46.729554Z","steps":["trace[1772646388] 'agreement among raft nodes before linearized reading' (duration: 110.693435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:46.729628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.946ms","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-08T12:56:46.729715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.278556ms","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-08T12:56:46.729882Z","caller":"traceutil/trace.go:171","msg":"trace[389247896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1988; }","duration":"119.487298ms","start":"2026-04-08T12:56:46.610376Z","end":"2026-04-08T12:56:46.729863Z","steps":["trace[389247896] 'agreement among raft nodes before linearized reading' (duration: 118.991922ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:46.729765Z","caller":"traceutil/trace.go:171","msg":"trace[995787637] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1988; }","duration":"119.181842ms","start":"2026-04-08T12:56:46.61056Z","end":"2026-04-08T12:56:46.729741Z","steps":["trace[995787637] 'agreement among raft nodes before linearized reading' (duration: 118.041475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:46.729385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.146843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:56:46.73022Z","caller":"traceutil/trace.go:171","msg":"trace[1942767626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1988; }","duration":"252.214297ms","start":"2026-04-08T12:56:46.477989Z","end":"2026-04-08T12:56:46.730203Z","steps":["trace[1942767626] 'agreement among raft nodes before linearized reading' (duration: 250.006516ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:15.614393Z","caller":"traceutil/trace.go:171","msg":"trace[421376329] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"123.268149ms","start":"2026-04-08T12:57:15.491093Z","end":"2026-04-08T12:57:15.614361Z","steps":["trace[421376329] 'process raft request' (duration: 123.12197ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:23.051953Z","caller":"traceutil/trace.go:171","msg":"trace[1210103423] linearizableReadLoop","detail":"{readStateIndex:2272; appliedIndex:2271; }","duration":"184.770848ms","start":"2026-04-08T12:57:22.867146Z","end":"2026-04-08T12:57:23.051917Z","steps":["trace[1210103423] 'read index received' (duration: 184.601497ms)","trace[1210103423] 'applied index is now lower than readState.Index' (duration: 168.021µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:23.051992Z","caller":"traceutil/trace.go:171","msg":"trace[2137658895] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"193.694986ms","start":"2026-04-08T12:57:22.858266Z","end":"2026-04-08T12:57:23.051961Z","steps":["trace[2137658895] 'process raft request' (duration: 193.477162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:23.052136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.97154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T12:57:23.052356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.346957ms","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-08T12:57:23.052408Z","caller":"traceutil/trace.go:171","msg":"trace[687764933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2190; }","duration":"136.411251ms","start":"2026-04-08T12:57:22.915983Z","end":"2026-04-08T12:57:23.052394Z","steps":["trace[687764933] 'agreement among raft nodes before linearized reading' (duration: 136.207888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:23.052517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.15859ms","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-08T12:57:23.052608Z","caller":"traceutil/trace.go:171","msg":"trace[490637648] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2190; }","duration":"129.278568ms","start":"2026-04-08T12:57:22.923312Z","end":"2026-04-08T12:57:23.052591Z","steps":["trace[490637648] 'agreement among raft nodes before linearized reading' (duration: 128.99635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:23.052697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.243344ms","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-08T12:57:23.052724Z","caller":"traceutil/trace.go:171","msg":"trace[1089445264] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2190; }","duration":"185.091458ms","start":"2026-04-08T12:57:22.867079Z","end":"2026-04-08T12:57:23.05217Z","steps":["trace[1089445264] 'agreement among raft nodes before linearized reading' (duration: 184.992252ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:23.052781Z","caller":"traceutil/trace.go:171","msg":"trace[1127136567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2190; }","duration":"134.492859ms","start":"2026-04-08T12:57:22.918267Z","end":"2026-04-08T12:57:23.05276Z","steps":["trace[1127136567] 'agreement among raft nodes before linearized reading' (duration: 134.092234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:23.053165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.488281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-08T12:57:23.053271Z","caller":"traceutil/trace.go:171","msg":"trace[331068187] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2190; }","duration":"129.63312ms","start":"2026-04-08T12:57:22.923622Z","end":"2026-04-08T12:57:23.053255Z","steps":["trace[331068187] 'agreement among raft nodes before linearized reading' (duration: 129.397975ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:23.235371Z","caller":"traceutil/trace.go:171","msg":"trace[52116523] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"176.71527ms","start":"2026-04-08T12:57:23.058341Z","end":"2026-04-08T12:57:23.235056Z","steps":["trace[52116523] 'process raft request' (duration: 176.518428ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:23.239973Z","caller":"traceutil/trace.go:171","msg":"trace[2021139059] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"181.459503ms","start":"2026-04-08T12:57:23.058477Z","end":"2026-04-08T12:57:23.239936Z","steps":["trace[2021139059] 'process raft request' (duration: 181.233129ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:23.240064Z","caller":"traceutil/trace.go:171","msg":"trace[629799582] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"180.179672ms","start":"2026-04-08T12:57:23.05986Z","end":"2026-04-08T12:57:23.24004Z","steps":["trace[629799582] 'process raft request' (duration: 179.952878ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:23.240226Z","caller":"traceutil/trace.go:171","msg":"trace[24248831] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"179.715772ms","start":"2026-04-08T12:57:23.06046Z","end":"2026-04-08T12:57:23.240176Z","steps":["trace[24248831] 'process raft request' (duration: 179.388561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:23.775864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.333999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:23.776197Z","caller":"traceutil/trace.go:171","msg":"trace[163819466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2195; }","duration":"295.717424ms","start":"2026-04-08T12:57:23.480458Z","end":"2026-04-08T12:57:23.776175Z","steps":["trace[163819466] 'range keys from in-memory index tree' (duration: 295.226852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:23.775946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.601142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a4630329cb3aef\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-08T12:57:23.776646Z","caller":"traceutil/trace.go:171","msg":"trace[1384672108] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a4630329cb3aef; range_end:; response_count:1; response_revision:2195; }","duration":"444.395613ms","start":"2026-04-08T12:57:23.332229Z","end":"2026-04-08T12:57:23.776625Z","steps":["trace[1384672108] 'range keys from in-memory index tree' (duration: 443.368937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:23.776716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:23.332207Z","time spent":"444.484559ms","remote":"127.0.0.1:43108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a4630329cb3aef\" "} {"level":"info","ts":"2026-04-08T12:57:23.956358Z","caller":"traceutil/trace.go:171","msg":"trace[989405027] linearizableReadLoop","detail":"{readStateIndex:2278; appliedIndex:2277; }","duration":"309.956484ms","start":"2026-04-08T12:57:23.646376Z","end":"2026-04-08T12:57:23.956333Z","steps":["trace[989405027] 'read index received' (duration: 309.831706ms)","trace[989405027] 'applied index is now lower than readState.Index' (duration: 123.678µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:23.956411Z","caller":"traceutil/trace.go:171","msg":"trace[1419933396] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"314.616452ms","start":"2026-04-08T12:57:23.641766Z","end":"2026-04-08T12:57:23.956383Z","steps":["trace[1419933396] 'process raft request' (duration: 314.398718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:23.956615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.231071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-04-08T12:57:23.956743Z","caller":"traceutil/trace.go:171","msg":"trace[349222731] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2196; }","duration":"310.381681ms","start":"2026-04-08T12:57:23.646318Z","end":"2026-04-08T12:57:23.9567Z","steps":["trace[349222731] 'agreement among raft nodes before linearized reading' (duration: 310.106474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:23.956826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:23.646296Z","time spent":"310.517139ms","remote":"127.0.0.1:43212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12406,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-08T12:57:23.956984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:23.641742Z","time spent":"314.876538ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:57:24.187559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:23.778341Z","time spent":"409.213436ms","remote":"127.0.0.1:43108","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-08T12:57:24.188063Z","caller":"traceutil/trace.go:171","msg":"trace[1286530339] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2279; }","duration":"231.526823ms","start":"2026-04-08T12:57:23.95651Z","end":"2026-04-08T12:57:24.188036Z","steps":["trace[1286530339] 'read index received' (duration: 231.517472ms)","trace[1286530339] 'applied index is now lower than readState.Index' (duration: 7.4µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:57:24.188248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.451054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:24.188379Z","caller":"traceutil/trace.go:171","msg":"trace[1454450156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2196; }","duration":"410.634047ms","start":"2026-04-08T12:57:23.777721Z","end":"2026-04-08T12:57:24.188355Z","steps":["trace[1454450156] 'agreement among raft nodes before linearized reading' (duration: 410.400361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:24.188441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:23.777696Z","time spent":"410.730633ms","remote":"127.0.0.1:43038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T12:57:24.196464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.441896ms","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-08T12:57:24.196591Z","caller":"traceutil/trace.go:171","msg":"trace[485636218] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2197; }","duration":"230.604496ms","start":"2026-04-08T12:57:23.965965Z","end":"2026-04-08T12:57:24.196569Z","steps":["trace[485636218] 'agreement among raft nodes before linearized reading' (duration: 230.415184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:24.196665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.875715ms","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-08T12:57:24.196737Z","caller":"traceutil/trace.go:171","msg":"trace[2107059683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2197; }","duration":"163.95645ms","start":"2026-04-08T12:57:24.032757Z","end":"2026-04-08T12:57:24.196713Z","steps":["trace[2107059683] 'agreement among raft nodes before linearized reading' (duration: 163.794319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:24.196758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.04576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T12:57:24.196873Z","caller":"traceutil/trace.go:171","msg":"trace[1010025635] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"168.717076ms","start":"2026-04-08T12:57:24.028033Z","end":"2026-04-08T12:57:24.19675Z","steps":["trace[1010025635] 'process raft request' (duration: 168.151759ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:24.197479Z","caller":"traceutil/trace.go:171","msg":"trace[491077908] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2197; }","duration":"136.198949ms","start":"2026-04-08T12:57:24.060656Z","end":"2026-04-08T12:57:24.196855Z","steps":["trace[491077908] 'agreement among raft nodes before linearized reading' (duration: 135.91303ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:24.379155Z","caller":"traceutil/trace.go:171","msg":"trace[772574131] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"177.020309ms","start":"2026-04-08T12:57:24.202111Z","end":"2026-04-08T12:57:24.379131Z","steps":["trace[772574131] 'process raft request' (duration: 176.982187ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:24.379305Z","caller":"traceutil/trace.go:171","msg":"trace[1921810895] linearizableReadLoop","detail":"{readStateIndex:2281; appliedIndex:2280; }","duration":"179.68684ms","start":"2026-04-08T12:57:24.199592Z","end":"2026-04-08T12:57:24.379279Z","steps":["trace[1921810895] 'read index received' (duration: 124.547802ms)","trace[1921810895] 'applied index is now lower than readState.Index' (duration: 55.136487ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:24.379324Z","caller":"traceutil/trace.go:171","msg":"trace[570535199] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"179.152285ms","start":"2026-04-08T12:57:24.200153Z","end":"2026-04-08T12:57:24.379305Z","steps":["trace[570535199] 'process raft request' (duration: 178.848276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:24.379417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.803377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:57:24.379446Z","caller":"traceutil/trace.go:171","msg":"trace[1174425509] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2200; }","duration":"179.878672ms","start":"2026-04-08T12:57:24.19956Z","end":"2026-04-08T12:57:24.379439Z","steps":["trace[1174425509] 'agreement among raft nodes before linearized reading' (duration: 179.798907ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:24.379473Z","caller":"traceutil/trace.go:171","msg":"trace[95674528] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"190.30104ms","start":"2026-04-08T12:57:24.18916Z","end":"2026-04-08T12:57:24.379461Z","steps":["trace[95674528] 'process raft request' (duration: 135.151211ms)","trace[95674528] 'compare' (duration: 54.347987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:58:42.57788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.204903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:58:42.57798Z","caller":"traceutil/trace.go:171","msg":"trace[348312752] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2723; }","duration":"173.381167ms","start":"2026-04-08T12:58:42.404579Z","end":"2026-04-08T12:58:42.57796Z","steps":["trace[348312752] 'count revisions from in-memory index tree' (duration: 172.952354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:58:43.152664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.447552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a46316dea156d9\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-04-08T12:58:43.152753Z","caller":"traceutil/trace.go:171","msg":"trace[890265822] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a46316dea156d9; range_end:; response_count:1; response_revision:2725; }","duration":"184.590823ms","start":"2026-04-08T12:58:42.968144Z","end":"2026-04-08T12:58:43.152735Z","steps":["trace[890265822] 'range keys from in-memory index tree' (duration: 184.214454ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:33.787677Z","caller":"traceutil/trace.go:171","msg":"trace[1586668765] linearizableReadLoop","detail":"{readStateIndex:3103; appliedIndex:3102; }","duration":"110.320648ms","start":"2026-04-08T12:59:33.677338Z","end":"2026-04-08T12:59:33.787659Z","steps":["trace[1586668765] 'read index received' (duration: 110.243971ms)","trace[1586668765] 'applied index is now lower than readState.Index' (duration: 75.857µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:59:33.787742Z","caller":"traceutil/trace.go:171","msg":"trace[872761230] transaction","detail":"{read_only:false; response_revision:2991; number_of_response:1; }","duration":"137.033826ms","start":"2026-04-08T12:59:33.650678Z","end":"2026-04-08T12:59:33.787712Z","steps":["trace[872761230] 'process raft request' (duration: 136.858413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:33.787828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.482611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-04-08T12:59:33.787858Z","caller":"traceutil/trace.go:171","msg":"trace[26618307] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2991; }","duration":"110.611917ms","start":"2026-04-08T12:59:33.677238Z","end":"2026-04-08T12:59:33.78785Z","steps":["trace[26618307] 'agreement among raft nodes before linearized reading' (duration: 110.500641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:07.485654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.910561ms","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-08T13:00:07.485756Z","caller":"traceutil/trace.go:171","msg":"trace[1666554170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3167; }","duration":"194.028539ms","start":"2026-04-08T13:00:07.291704Z","end":"2026-04-08T13:00:07.485733Z","steps":["trace[1666554170] 'range keys from in-memory index tree' (duration: 193.663235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:00:07.485918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.182375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T13:00:07.486067Z","caller":"traceutil/trace.go:171","msg":"trace[1484999945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3167; }","duration":"182.499511ms","start":"2026-04-08T13:00:07.303553Z","end":"2026-04-08T13:00:07.486052Z","steps":["trace[1484999945] 'range keys from in-memory index tree' (duration: 182.015048ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:09.50845Z","caller":"traceutil/trace.go:171","msg":"trace[59120339] transaction","detail":"{read_only:false; response_revision:3177; number_of_response:1; }","duration":"116.404002ms","start":"2026-04-08T13:00:09.392018Z","end":"2026-04-08T13:00:09.508422Z","steps":["trace[59120339] 'process raft request' (duration: 116.161895ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:00:59.547952Z","caller":"traceutil/trace.go:171","msg":"trace[950002446] transaction","detail":"{read_only:false; response_revision:3523; number_of_response:1; }","duration":"116.30436ms","start":"2026-04-08T13:00:59.431604Z","end":"2026-04-08T13:00:59.547908Z","steps":["trace[950002446] 'process raft request' (duration: 112.23283ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:00.948719Z","caller":"traceutil/trace.go:171","msg":"trace[569877288] transaction","detail":"{read_only:false; response_revision:3533; number_of_response:1; }","duration":"120.525794ms","start":"2026-04-08T13:01:00.82817Z","end":"2026-04-08T13:01:00.948696Z","steps":["trace[569877288] 'process raft request' (duration: 55.227023ms)","trace[569877288] 'compare' (duration: 65.175569ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:01:04.521819Z","caller":"traceutil/trace.go:171","msg":"trace[852303685] transaction","detail":"{read_only:false; response_revision:3549; number_of_response:1; }","duration":"213.154324ms","start":"2026-04-08T13:01:04.308634Z","end":"2026-04-08T13:01:04.521789Z","steps":["trace[852303685] 'process raft request' (duration: 212.968602ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:18.687495Z","caller":"traceutil/trace.go:171","msg":"trace[1559005733] transaction","detail":"{read_only:false; response_revision:3628; number_of_response:1; }","duration":"114.816572ms","start":"2026-04-08T13:01:18.572653Z","end":"2026-04-08T13:01:18.68747Z","steps":["trace[1559005733] 'process raft request' (duration: 114.666669ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:05.05574Z","caller":"traceutil/trace.go:171","msg":"trace[1462038731] transaction","detail":"{read_only:false; response_revision:3948; number_of_response:1; }","duration":"148.706401ms","start":"2026-04-08T13:02:04.907003Z","end":"2026-04-08T13:02:05.055709Z","steps":["trace[1462038731] 'process raft request' (duration: 148.425951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:05.592111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495241267541861,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T13:02:06.092875Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495241267541861,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T13:02:06.593913Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495241267541861,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T13:02:06.68721Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.623137057s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T13:02:06.687702Z","caller":"traceutil/trace.go:171","msg":"trace[693319545] transaction","detail":"{read_only:false; response_revision:3949; number_of_response:1; }","duration":"1.623793159s","start":"2026-04-08T13:02:05.063881Z","end":"2026-04-08T13:02:06.687674Z","steps":["trace[693319545] 'process raft request' (duration: 1.623655334s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:06.687841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.063854Z","time spent":"1.623931844s","remote":"127.0.0.1:43140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":27126,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T13:02:06.885714Z","caller":"traceutil/trace.go:171","msg":"trace[1572719793] transaction","detail":"{read_only:false; response_revision:3950; number_of_response:1; }","duration":"1.652811279s","start":"2026-04-08T13:02:05.232885Z","end":"2026-04-08T13:02:06.885696Z","steps":["trace[1572719793] 'process raft request' (duration: 1.558449374s)","trace[1572719793] 'compare' (duration: 94.21592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:06.885828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.232865Z","time spent":"1.652904812s","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:02:07.108959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.24562ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T13:02:07.109098Z","caller":"traceutil/trace.go:171","msg":"trace[1375523530] linearizableReadLoop","detail":"{readStateIndex:4101; appliedIndex:4098; }","duration":"2.018145259s","start":"2026-04-08T13:02:05.090928Z","end":"2026-04-08T13:02:07.109074Z","steps":["trace[1375523530] 'read index received' (duration: 1.59658473s)","trace[1375523530] 'applied index is now lower than readState.Index' (duration: 421.558929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:07.109367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.018424579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-08T13:02:07.109466Z","caller":"traceutil/trace.go:171","msg":"trace[1982199555] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3950; }","duration":"2.018565574s","start":"2026-04-08T13:02:05.09087Z","end":"2026-04-08T13:02:07.109436Z","steps":["trace[1982199555] 'agreement among raft nodes before linearized reading' (duration: 2.018310024s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.109534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.090844Z","time spent":"2.018674918s","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-08T13:02:07.110059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002222903s","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-08T13:02:07.110125Z","caller":"traceutil/trace.go:171","msg":"trace[1893121761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3951; }","duration":"1.002307875s","start":"2026-04-08T13:02:06.107797Z","end":"2026-04-08T13:02:07.110104Z","steps":["trace[1893121761] 'agreement among raft nodes before linearized reading' (duration: 1.002094778s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.110157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.107779Z","time spent":"1.002369627s","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T13:02:07.11035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.960891195s","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-08T13:02:07.110422Z","caller":"traceutil/trace.go:171","msg":"trace[1037663973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3951; }","duration":"1.960996709s","start":"2026-04-08T13:02:05.149404Z","end":"2026-04-08T13:02:07.1104Z","steps":["trace[1037663973] 'agreement among raft nodes before linearized reading' (duration: 1.960486392s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.11046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.149388Z","time spent":"1.961063712s","remote":"127.0.0.1:43282","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":"warn","ts":"2026-04-08T13:02:07.110525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.823385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:02:07.110563Z","caller":"traceutil/trace.go:171","msg":"trace[92233967] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:3951; }","duration":"660.892748ms","start":"2026-04-08T13:02:06.449658Z","end":"2026-04-08T13:02:07.110551Z","steps":["trace[92233967] 'agreement among raft nodes before linearized reading' (duration: 660.836606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.110592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.449637Z","time spent":"660.946989ms","remote":"127.0.0.1:45248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-08T13:02:07.110682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.20768769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:02:07.110721Z","caller":"traceutil/trace.go:171","msg":"trace[2084079928] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:3951; }","duration":"1.207726382s","start":"2026-04-08T13:02:05.902982Z","end":"2026-04-08T13:02:07.110708Z","steps":["trace[2084079928] 'agreement among raft nodes before linearized reading' (duration: 1.20766636s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.110739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.181633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:02:07.110753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.902934Z","time spent":"1.207810714s","remote":"127.0.0.1:35544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-04-08T13:02:07.110768Z","caller":"traceutil/trace.go:171","msg":"trace[429370527] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:3951; }","duration":"950.222784ms","start":"2026-04-08T13:02:06.160537Z","end":"2026-04-08T13:02:07.110759Z","steps":["trace[429370527] 'agreement among raft nodes before linearized reading' (duration: 950.174643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.110796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.160528Z","time spent":"950.261045ms","remote":"127.0.0.1:43264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-08T13:02:07.110952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.630814951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:02:07.110977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.034122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-08T13:02:07.110984Z","caller":"traceutil/trace.go:171","msg":"trace[943830380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3951; }","duration":"1.630889634s","start":"2026-04-08T13:02:05.480086Z","end":"2026-04-08T13:02:07.110975Z","steps":["trace[943830380] 'agreement among raft nodes before linearized reading' (duration: 1.630839372s)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:07.111006Z","caller":"traceutil/trace.go:171","msg":"trace[54650500] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3951; }","duration":"953.090453ms","start":"2026-04-08T13:02:06.157908Z","end":"2026-04-08T13:02:07.110998Z","steps":["trace[54650500] 'agreement among raft nodes before linearized reading' (duration: 953.00234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.111013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.480062Z","time spent":"1.630945555s","remote":"127.0.0.1:43042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T13:02:07.111033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.157897Z","time spent":"953.130444ms","remote":"127.0.0.1:43212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-08T13:02:07.111189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.836680336s","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-08T13:02:07.111219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.007288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T13:02:07.111247Z","caller":"traceutil/trace.go:171","msg":"trace[232676804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3951; }","duration":"998.055249ms","start":"2026-04-08T13:02:06.113184Z","end":"2026-04-08T13:02:07.111239Z","steps":["trace[232676804] 'agreement among raft nodes before linearized reading' (duration: 997.973736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.111271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:06.113168Z","time spent":"998.097931ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T13:02:07.111292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.861384239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-08T13:02:07.11132Z","caller":"traceutil/trace.go:171","msg":"trace[1778969751] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3951; }","duration":"1.86140969s","start":"2026-04-08T13:02:05.249901Z","end":"2026-04-08T13:02:07.111311Z","steps":["trace[1778969751] 'agreement among raft nodes before linearized reading' (duration: 1.861337518s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.111346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.249842Z","time spent":"1.861497873s","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T13:02:07.111461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.874171386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-08T13:02:07.111493Z","caller":"traceutil/trace.go:171","msg":"trace[2045131669] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3951; }","duration":"1.874226148s","start":"2026-04-08T13:02:05.237256Z","end":"2026-04-08T13:02:07.111482Z","steps":["trace[2045131669] 'agreement among raft nodes before linearized reading' (duration: 1.874148305s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.111525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.237244Z","time spent":"1.874273459s","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-08T13:02:07.111219Z","caller":"traceutil/trace.go:171","msg":"trace[1121473504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3951; }","duration":"1.836742379s","start":"2026-04-08T13:02:05.274467Z","end":"2026-04-08T13:02:07.111209Z","steps":["trace[1121473504] 'agreement among raft nodes before linearized reading' (duration: 1.836656806s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.111658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.274446Z","time spent":"1.837199054s","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T13:02:07.111736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.896609052s","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-08T13:02:07.111767Z","caller":"traceutil/trace.go:171","msg":"trace[601748313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3951; }","duration":"1.896674544s","start":"2026-04-08T13:02:05.215083Z","end":"2026-04-08T13:02:07.111758Z","steps":["trace[601748313] 'agreement among raft nodes before linearized reading' (duration: 1.896576701s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:07.111799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:05.215063Z","time spent":"1.896727177s","remote":"127.0.0.1:43282","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":"info","ts":"2026-04-08T13:02:13.105104Z","caller":"traceutil/trace.go:171","msg":"trace[738350166] linearizableReadLoop","detail":"{readStateIndex:4136; appliedIndex:4135; }","duration":"476.483385ms","start":"2026-04-08T13:02:12.628587Z","end":"2026-04-08T13:02:13.105071Z","steps":["trace[738350166] 'read index received' (duration: 476.449803ms)","trace[738350166] 'applied index is now lower than readState.Index' (duration: 32.361µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:13.105145Z","caller":"traceutil/trace.go:171","msg":"trace[272433080] transaction","detail":"{read_only:false; number_of_response:0; response_revision:3983; }","duration":"577.081281ms","start":"2026-04-08T13:02:12.528024Z","end":"2026-04-08T13:02:13.105105Z","steps":["trace[272433080] 'process raft request' (duration: 576.917295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:13.105281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.679751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:02:13.105286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:12.528001Z","time spent":"577.207245ms","remote":"127.0.0.1:43078","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T13:02:13.105343Z","caller":"traceutil/trace.go:171","msg":"trace[1642700621] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:3983; }","duration":"476.788476ms","start":"2026-04-08T13:02:12.628541Z","end":"2026-04-08T13:02:13.105329Z","steps":["trace[1642700621] 'agreement among raft nodes before linearized reading' (duration: 476.667661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:13.105394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:12.628524Z","time spent":"476.857298ms","remote":"127.0.0.1:43156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-08T13:02:13.106432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.26645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:02:13.106486Z","caller":"traceutil/trace.go:171","msg":"trace[643221966] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:3984; }","duration":"473.365934ms","start":"2026-04-08T13:02:12.633105Z","end":"2026-04-08T13:02:13.106471Z","steps":["trace[643221966] 'agreement among raft nodes before linearized reading' (duration: 473.26976ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:13.106491Z","caller":"traceutil/trace.go:171","msg":"trace[1635945998] transaction","detail":"{read_only:false; response_revision:3984; number_of_response:1; }","duration":"201.523808ms","start":"2026-04-08T13:02:12.904943Z","end":"2026-04-08T13:02:13.106467Z","steps":["trace[1635945998] 'process raft request' (duration: 201.321091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:13.106536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:12.633084Z","time spent":"473.439015ms","remote":"127.0.0.1:41634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-08T13:02:37.284628Z","caller":"traceutil/trace.go:171","msg":"trace[1363171920] transaction","detail":"{read_only:false; response_revision:4464; number_of_response:1; }","duration":"133.797182ms","start":"2026-04-08T13:02:37.150792Z","end":"2026-04-08T13:02:37.28459Z","steps":["trace[1363171920] 'process raft request' (duration: 49.113827ms)","trace[1363171920] 'compare' (duration: 84.219876ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:37.64407Z","caller":"traceutil/trace.go:171","msg":"trace[1604583422] transaction","detail":"{read_only:false; response_revision:4477; number_of_response:1; }","duration":"230.108767ms","start":"2026-04-08T13:02:37.413938Z","end":"2026-04-08T13:02:37.644046Z","steps":["trace[1604583422] 'process raft request' (duration: 229.92739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:37.644507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.154243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:9425"} {"level":"info","ts":"2026-04-08T13:02:37.644553Z","caller":"traceutil/trace.go:171","msg":"trace[1944539550] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:4477; }","duration":"100.266287ms","start":"2026-04-08T13:02:37.544277Z","end":"2026-04-08T13:02:37.644543Z","steps":["trace[1944539550] 'agreement among raft nodes before linearized reading' (duration: 100.063449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:38.949773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.774549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-68f177ac-73c9-4537-b42e-ea0ba05e510e\" ","response":"range_response_count:1 size:3894"} {"level":"info","ts":"2026-04-08T13:02:38.94987Z","caller":"traceutil/trace.go:171","msg":"trace[563061972] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-68f177ac-73c9-4537-b42e-ea0ba05e510e; range_end:; response_count:1; response_revision:4546; }","duration":"114.914965ms","start":"2026-04-08T13:02:38.834934Z","end":"2026-04-08T13:02:38.949849Z","steps":["trace[563061972] 'range keys from in-memory index tree' (duration: 114.615503ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.301175Z","caller":"traceutil/trace.go:171","msg":"trace[455077853] transaction","detail":"{read_only:false; response_revision:4547; number_of_response:1; }","duration":"250.906523ms","start":"2026-04-08T13:02:39.050244Z","end":"2026-04-08T13:02:39.301151Z","steps":["trace[455077853] 'process raft request' (duration: 250.723476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.518541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.137268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:02:39.518747Z","caller":"traceutil/trace.go:171","msg":"trace[230400265] linearizableReadLoop","detail":"{readStateIndex:4722; appliedIndex:4720; }","duration":"217.618636ms","start":"2026-04-08T13:02:39.301113Z","end":"2026-04-08T13:02:39.518732Z","steps":["trace[230400265] 'read index received' (duration: 77.219777ms)","trace[230400265] 'applied index is now lower than readState.Index' (duration: 140.397859ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:39.518838Z","caller":"traceutil/trace.go:171","msg":"trace[1343358844] transaction","detail":"{read_only:false; response_revision:4548; number_of_response:1; }","duration":"228.675348ms","start":"2026-04-08T13:02:39.290151Z","end":"2026-04-08T13:02:39.518827Z","steps":["trace[1343358844] 'process raft request' (duration: 88.177563ms)","trace[1343358844] 'compare' (duration: 139.978722ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:39.519038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.116214ms","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-08T13:02:39.519068Z","caller":"traceutil/trace.go:171","msg":"trace[309872513] transaction","detail":"{read_only:false; response_revision:4549; number_of_response:1; }","duration":"228.7481ms","start":"2026-04-08T13:02:39.290289Z","end":"2026-04-08T13:02:39.519037Z","steps":["trace[309872513] 'process raft request' (duration: 228.352673ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.51911Z","caller":"traceutil/trace.go:171","msg":"trace[1217420875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4549; }","duration":"222.220709ms","start":"2026-04-08T13:02:39.296871Z","end":"2026-04-08T13:02:39.519092Z","steps":["trace[1217420875] 'agreement among raft nodes before linearized reading' (duration: 222.035431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.519297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.128543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-04-08T13:02:39.519217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.452351ms","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-08T13:02:39.519334Z","caller":"traceutil/trace.go:171","msg":"trace[181509389] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4549; }","duration":"173.200216ms","start":"2026-04-08T13:02:39.346122Z","end":"2026-04-08T13:02:39.519323Z","steps":["trace[181509389] 'agreement among raft nodes before linearized reading' (duration: 173.111622ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.519357Z","caller":"traceutil/trace.go:171","msg":"trace[1974422660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4549; }","duration":"170.605888ms","start":"2026-04-08T13:02:39.348738Z","end":"2026-04-08T13:02:39.519344Z","steps":["trace[1974422660] 'agreement among raft nodes before linearized reading' (duration: 170.385159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.519449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.640371ms","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-08T13:02:39.519478Z","caller":"traceutil/trace.go:171","msg":"trace[63029811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4549; }","duration":"171.688943ms","start":"2026-04-08T13:02:39.347781Z","end":"2026-04-08T13:02:39.51947Z","steps":["trace[63029811] 'agreement among raft nodes before linearized reading' (duration: 171.61766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.519588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.241869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-08T13:02:39.519617Z","caller":"traceutil/trace.go:171","msg":"trace[721954007] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4549; }","duration":"168.280971ms","start":"2026-04-08T13:02:39.351328Z","end":"2026-04-08T13:02:39.519609Z","steps":["trace[721954007] 'agreement among raft nodes before linearized reading' (duration: 168.206828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.519859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.662747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T13:02:39.519898Z","caller":"traceutil/trace.go:171","msg":"trace[1457321164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4549; }","duration":"180.719439ms","start":"2026-04-08T13:02:39.339168Z","end":"2026-04-08T13:02:39.519887Z","steps":["trace[1457321164] 'agreement among raft nodes before linearized reading' (duration: 180.622045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:39.520128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.128821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T13:02:39.520188Z","caller":"traceutil/trace.go:171","msg":"trace[571332451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4549; }","duration":"196.222034ms","start":"2026-04-08T13:02:39.323952Z","end":"2026-04-08T13:02:39.520174Z","steps":["trace[571332451] 'agreement among raft nodes before linearized reading' (duration: 195.101538ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.674304Z","caller":"traceutil/trace.go:171","msg":"trace[1077967368] transaction","detail":"{read_only:false; response_revision:4550; number_of_response:1; }","duration":"149.905896ms","start":"2026-04-08T13:02:39.524373Z","end":"2026-04-08T13:02:39.674279Z","steps":["trace[1077967368] 'process raft request' (duration: 148.991058ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.715097Z","caller":"traceutil/trace.go:171","msg":"trace[1130351006] transaction","detail":"{read_only:false; response_revision:4553; number_of_response:1; }","duration":"190.551049ms","start":"2026-04-08T13:02:39.524529Z","end":"2026-04-08T13:02:39.71508Z","steps":["trace[1130351006] 'process raft request' (duration: 190.501217ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.715159Z","caller":"traceutil/trace.go:171","msg":"trace[50929718] transaction","detail":"{read_only:false; response_revision:4551; number_of_response:1; }","duration":"190.683435ms","start":"2026-04-08T13:02:39.524434Z","end":"2026-04-08T13:02:39.715117Z","steps":["trace[50929718] 'process raft request' (duration: 190.464236ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.715219Z","caller":"traceutil/trace.go:171","msg":"trace[877006286] transaction","detail":"{read_only:false; response_revision:4552; number_of_response:1; }","duration":"190.749598ms","start":"2026-04-08T13:02:39.524462Z","end":"2026-04-08T13:02:39.715211Z","steps":["trace[877006286] 'process raft request' (duration: 190.537019ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.715419Z","caller":"traceutil/trace.go:171","msg":"trace[1438972775] transaction","detail":"{read_only:false; response_revision:4554; number_of_response:1; }","duration":"190.564269ms","start":"2026-04-08T13:02:39.524833Z","end":"2026-04-08T13:02:39.715398Z","steps":["trace[1438972775] 'process raft request' (duration: 190.221945ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.715681Z","caller":"traceutil/trace.go:171","msg":"trace[1390853142] transaction","detail":"{read_only:false; response_revision:4555; number_of_response:1; }","duration":"190.706885ms","start":"2026-04-08T13:02:39.524948Z","end":"2026-04-08T13:02:39.715655Z","steps":["trace[1390853142] 'process raft request' (duration: 190.34738ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:39.715793Z","caller":"traceutil/trace.go:171","msg":"trace[260178259] transaction","detail":"{read_only:false; response_revision:4556; number_of_response:1; }","duration":"190.050188ms","start":"2026-04-08T13:02:39.52573Z","end":"2026-04-08T13:02:39.71578Z","steps":["trace[260178259] 'process raft request' (duration: 189.799448ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:47.024923Z","caller":"traceutil/trace.go:171","msg":"trace[1658137088] transaction","detail":"{read_only:false; response_revision:4678; number_of_response:1; }","duration":"195.080508ms","start":"2026-04-08T13:02:46.829821Z","end":"2026-04-08T13:02:47.024901Z","steps":["trace[1658137088] 'process raft request' (duration: 100.9902ms)","trace[1658137088] 'compare' (duration: 93.985834ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:47.266996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.066894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:2161"} {"level":"info","ts":"2026-04-08T13:02:47.267161Z","caller":"traceutil/trace.go:171","msg":"trace[1463579552] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4679; }","duration":"225.106616ms","start":"2026-04-08T13:02:47.04202Z","end":"2026-04-08T13:02:47.267126Z","steps":["trace[1463579552] 'process raft request' (duration: 119.827952ms)","trace[1463579552] 'compare' (duration: 104.995432ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:47.267212Z","caller":"traceutil/trace.go:171","msg":"trace[2125877373] transaction","detail":"{read_only:false; response_revision:4680; number_of_response:1; }","duration":"183.25836ms","start":"2026-04-08T13:02:47.083936Z","end":"2026-04-08T13:02:47.267194Z","steps":["trace[2125877373] 'process raft request' (duration: 183.176066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:47.267701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.451291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-08T13:02:47.267833Z","caller":"traceutil/trace.go:171","msg":"trace[1461893207] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4680; }","duration":"225.569017ms","start":"2026-04-08T13:02:47.042207Z","end":"2026-04-08T13:02:47.267776Z","steps":["trace[1461893207] 'agreement among raft nodes before linearized reading' (duration: 225.255512ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:47.267425Z","caller":"traceutil/trace.go:171","msg":"trace[308111028] linearizableReadLoop","detail":"{readStateIndex:4858; appliedIndex:4856; }","duration":"225.163368ms","start":"2026-04-08T13:02:47.04222Z","end":"2026-04-08T13:02:47.267384Z","steps":["trace[308111028] 'read index received' (duration: 37.041238ms)","trace[308111028] 'applied index is now lower than readState.Index' (duration: 188.115029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:47.307403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.866631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-h6hdr-tls\" ","response":"range_response_count:1 size:4432"} {"level":"info","ts":"2026-04-08T13:02:47.307473Z","caller":"traceutil/trace.go:171","msg":"trace[848099780] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-h6hdr-tls; range_end:; response_count:1; response_revision:4681; }","duration":"219.976755ms","start":"2026-04-08T13:02:47.087476Z","end":"2026-04-08T13:02:47.307453Z","steps":["trace[848099780] 'agreement among raft nodes before linearized reading' (duration: 219.789237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:47.307463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.73523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-h6hdr-tls-vq2xp\" ","response":"range_response_count:1 size:2625"} {"level":"info","ts":"2026-04-08T13:02:47.30753Z","caller":"traceutil/trace.go:171","msg":"trace[1795530178] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-h6hdr-tls-vq2xp; range_end:; response_count:1; response_revision:4681; }","duration":"221.847006ms","start":"2026-04-08T13:02:47.085667Z","end":"2026-04-08T13:02:47.307514Z","steps":["trace[1795530178] 'agreement among raft nodes before linearized reading' (duration: 221.636777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:47.307519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.509928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-08T13:02:47.307594Z","caller":"traceutil/trace.go:171","msg":"trace[1044939936] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4681; }","duration":"239.658824ms","start":"2026-04-08T13:02:47.067915Z","end":"2026-04-08T13:02:47.307574Z","steps":["trace[1044939936] 'agreement among raft nodes before linearized reading' (duration: 239.476456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:47.307731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.680403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:02:47.307772Z","caller":"traceutil/trace.go:171","msg":"trace[176881779] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:4681; }","duration":"131.723595ms","start":"2026-04-08T13:02:47.176037Z","end":"2026-04-08T13:02:47.307761Z","steps":["trace[176881779] 'agreement among raft nodes before linearized reading' (duration: 131.623301ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:47.514086Z","caller":"traceutil/trace.go:171","msg":"trace[27142819] linearizableReadLoop","detail":"{readStateIndex:4863; appliedIndex:4862; }","duration":"149.845128ms","start":"2026-04-08T13:02:47.36421Z","end":"2026-04-08T13:02:47.514055Z","steps":["trace[27142819] 'read index received' (duration: 123.403887ms)","trace[27142819] 'applied index is now lower than readState.Index' (duration: 26.44004ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:47.514358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.12086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-04-08T13:02:47.514131Z","caller":"traceutil/trace.go:171","msg":"trace[824809997] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"151.29945ms","start":"2026-04-08T13:02:47.362806Z","end":"2026-04-08T13:02:47.514105Z","steps":["trace[824809997] 'process raft request' (duration: 124.845158ms)","trace[824809997] 'compare' (duration: 26.274194ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:47.514407Z","caller":"traceutil/trace.go:171","msg":"trace[1268158783] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4684; }","duration":"150.207684ms","start":"2026-04-08T13:02:47.364185Z","end":"2026-04-08T13:02:47.514392Z","steps":["trace[1268158783] 'agreement among raft nodes before linearized reading' (duration: 150.033216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:47.514383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.141831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-08T13:02:47.514608Z","caller":"traceutil/trace.go:171","msg":"trace[765973175] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4684; }","duration":"150.386141ms","start":"2026-04-08T13:02:47.364197Z","end":"2026-04-08T13:02:47.514583Z","steps":["trace[765973175] 'agreement among raft nodes before linearized reading' (duration: 149.930351ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:48.890031Z","caller":"traceutil/trace.go:171","msg":"trace[477736974] transaction","detail":"{read_only:false; response_revision:4698; number_of_response:1; }","duration":"172.655753ms","start":"2026-04-08T13:02:48.71735Z","end":"2026-04-08T13:02:48.890006Z","steps":["trace[477736974] 'process raft request' (duration: 172.452355ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:48.995811Z","caller":"traceutil/trace.go:171","msg":"trace[521866797] linearizableReadLoop","detail":"{readStateIndex:4878; appliedIndex:4876; }","duration":"265.179822ms","start":"2026-04-08T13:02:48.730607Z","end":"2026-04-08T13:02:48.995787Z","steps":["trace[521866797] 'read index received' (duration: 159.277503ms)","trace[521866797] 'applied index is now lower than readState.Index' (duration: 105.901369ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:48.995903Z","caller":"traceutil/trace.go:171","msg":"trace[892496922] transaction","detail":"{read_only:false; response_revision:4699; number_of_response:1; }","duration":"272.698099ms","start":"2026-04-08T13:02:48.723175Z","end":"2026-04-08T13:02:48.995873Z","steps":["trace[892496922] 'process raft request' (duration: 272.454179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:48.995949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.325609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:02:48.995986Z","caller":"traceutil/trace.go:171","msg":"trace[12048378] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:4699; }","duration":"265.430813ms","start":"2026-04-08T13:02:48.730544Z","end":"2026-04-08T13:02:48.995975Z","steps":["trace[12048378] 'agreement among raft nodes before linearized reading' (duration: 265.34852ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:52.410535Z","caller":"traceutil/trace.go:171","msg":"trace[1658339870] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"116.584965ms","start":"2026-04-08T13:02:52.293926Z","end":"2026-04-08T13:02:52.410511Z","steps":["trace[1658339870] 'process raft request' (duration: 116.029331ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:52.903046Z","caller":"traceutil/trace.go:171","msg":"trace[1643437199] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4955; }","duration":"422.440209ms","start":"2026-04-08T13:02:52.480577Z","end":"2026-04-08T13:02:52.903017Z","steps":["trace[1643437199] 'read index received' (duration: 394.749579ms)","trace[1643437199] 'applied index is now lower than readState.Index' (duration: 27.68941ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:52.903153Z","caller":"traceutil/trace.go:171","msg":"trace[17292059] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"476.700329ms","start":"2026-04-08T13:02:52.426408Z","end":"2026-04-08T13:02:52.903108Z","steps":["trace[17292059] 'process raft request' (duration: 448.988518ms)","trace[17292059] 'compare' (duration: 27.493352ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:52.903232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.631087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:02:52.90327Z","caller":"traceutil/trace.go:171","msg":"trace[931118736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4776; }","duration":"422.725572ms","start":"2026-04-08T13:02:52.480532Z","end":"2026-04-08T13:02:52.903257Z","steps":["trace[931118736] 'agreement among raft nodes before linearized reading' (duration: 422.645208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:52.903283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:52.426395Z","time spent":"476.818005ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:02:52.903276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.309678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f37aef6e-1374-4c17-aa4c-9cc927bd91bf\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-04-08T13:02:52.903303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:52.480515Z","time spent":"422.780504ms","remote":"127.0.0.1:43042","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-08T13:02:52.903336Z","caller":"traceutil/trace.go:171","msg":"trace[1722474773] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f37aef6e-1374-4c17-aa4c-9cc927bd91bf; range_end:; response_count:1; response_revision:4776; }","duration":"159.404401ms","start":"2026-04-08T13:02:52.743918Z","end":"2026-04-08T13:02:52.903322Z","steps":["trace[1722474773] 'agreement among raft nodes before linearized reading' (duration: 159.253995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:55.068447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.807533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:02:55.068627Z","caller":"traceutil/trace.go:171","msg":"trace[1524119967] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"446.544067ms","start":"2026-04-08T13:02:54.622066Z","end":"2026-04-08T13:02:55.06861Z","steps":["trace[1524119967] 'process raft request' (duration: 307.514143ms)","trace[1524119967] 'compare' (duration: 138.228898ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:02:55.068681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:54.622052Z","time spent":"446.60594ms","remote":"127.0.0.1:43108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T13:02:55.069089Z","caller":"traceutil/trace.go:171","msg":"trace[256934709] linearizableReadLoop","detail":"{readStateIndex:4980; appliedIndex:4978; }","duration":"422.062139ms","start":"2026-04-08T13:02:54.647018Z","end":"2026-04-08T13:02:55.06908Z","steps":["trace[256934709] 'read index received' (duration: 282.66312ms)","trace[256934709] 'applied index is now lower than readState.Index' (duration: 139.398349ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:55.069262Z","caller":"traceutil/trace.go:171","msg":"trace[963196996] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"439.213841ms","start":"2026-04-08T13:02:54.630041Z","end":"2026-04-08T13:02:55.069255Z","steps":["trace[963196996] 'process raft request' (duration: 438.523931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:55.069312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:54.630022Z","time spent":"439.256123ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:02:55.069535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.499989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:02:55.069569Z","caller":"traceutil/trace.go:171","msg":"trace[822052660] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:4802; }","duration":"422.582913ms","start":"2026-04-08T13:02:54.646976Z","end":"2026-04-08T13:02:55.069559Z","steps":["trace[822052660] 'agreement among raft nodes before linearized reading' (duration: 422.505979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:55.069595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:54.646954Z","time spent":"422.634896ms","remote":"127.0.0.1:45066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-04-08T13:02:55.069718Z","caller":"traceutil/trace.go:171","msg":"trace[1629636665] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"155.698344ms","start":"2026-04-08T13:02:54.91401Z","end":"2026-04-08T13:02:55.069708Z","steps":["trace[1629636665] 'process raft request' (duration: 155.029814ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:55.069721Z","caller":"traceutil/trace.go:171","msg":"trace[1461538424] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"256.258409ms","start":"2026-04-08T13:02:54.81345Z","end":"2026-04-08T13:02:55.069708Z","steps":["trace[1461538424] 'process raft request' (duration: 255.553487ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:02.076843Z","caller":"traceutil/trace.go:171","msg":"trace[1859157417] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"130.030688ms","start":"2026-04-08T13:03:01.946786Z","end":"2026-04-08T13:03:02.076817Z","steps":["trace[1859157417] 'process raft request' (duration: 126.957457ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:02.189901Z","caller":"traceutil/trace.go:171","msg":"trace[1741389435] linearizableReadLoop","detail":"{readStateIndex:5102; appliedIndex:5100; }","duration":"239.560716ms","start":"2026-04-08T13:03:01.950317Z","end":"2026-04-08T13:03:02.189877Z","steps":["trace[1741389435] 'read index received' (duration: 123.438877ms)","trace[1741389435] 'applied index is now lower than readState.Index' (duration: 116.120569ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:03:02.190211Z","caller":"traceutil/trace.go:171","msg":"trace[353634069] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"239.952354ms","start":"2026-04-08T13:03:01.950246Z","end":"2026-04-08T13:03:02.190198Z","steps":["trace[353634069] 'process raft request' (duration: 238.833553ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:02.190467Z","caller":"traceutil/trace.go:171","msg":"trace[2143368261] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"240.0796ms","start":"2026-04-08T13:03:01.950354Z","end":"2026-04-08T13:03:02.190434Z","steps":["trace[2143368261] 'process raft request' (duration: 239.475632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:02.190558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.206616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-08T13:03:02.190599Z","caller":"traceutil/trace.go:171","msg":"trace[1196495172] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4918; }","duration":"240.285929ms","start":"2026-04-08T13:03:01.950299Z","end":"2026-04-08T13:03:02.190585Z","steps":["trace[1196495172] 'agreement among raft nodes before linearized reading' (duration: 240.136422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:02.190791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.202789ms","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-08T13:03:02.190863Z","caller":"traceutil/trace.go:171","msg":"trace[1339890250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4918; }","duration":"239.302495ms","start":"2026-04-08T13:03:01.951542Z","end":"2026-04-08T13:03:02.190845Z","steps":["trace[1339890250] 'agreement among raft nodes before linearized reading' (duration: 239.057423ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:05.953587Z","caller":"traceutil/trace.go:171","msg":"trace[151816816] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"234.78178ms","start":"2026-04-08T13:03:05.718769Z","end":"2026-04-08T13:03:05.95355Z","steps":["trace[151816816] 'process raft request' (duration: 234.551019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.444937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.324316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:03:06.445033Z","caller":"traceutil/trace.go:171","msg":"trace[541063682] linearizableReadLoop","detail":"{readStateIndex:5126; appliedIndex:5124; }","duration":"650.682837ms","start":"2026-04-08T13:03:05.794335Z","end":"2026-04-08T13:03:06.445017Z","steps":["trace[541063682] 'read index received' (duration: 159.023979ms)","trace[541063682] 'applied index is now lower than readState.Index' (duration: 491.657508ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:03:06.445299Z","caller":"traceutil/trace.go:171","msg":"trace[1677752928] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"709.714545ms","start":"2026-04-08T13:03:05.735571Z","end":"2026-04-08T13:03:06.445286Z","steps":["trace[1677752928] 'process raft request' (duration: 477.956629ms)","trace[1677752928] 'compare' (duration: 230.911327ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:03:06.445367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:05.73554Z","time spent":"709.793298ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:03:06.445623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.269354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-04-08T13:03:06.445713Z","caller":"traceutil/trace.go:171","msg":"trace[2113150487] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4941; }","duration":"651.414251ms","start":"2026-04-08T13:03:05.794276Z","end":"2026-04-08T13:03:06.445691Z","steps":["trace[2113150487] 'agreement among raft nodes before linearized reading' (duration: 651.078715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.445818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:05.794253Z","time spent":"651.547907ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T13:03:06.544973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.959122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-08T13:03:06.545006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.973915ms","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-08T13:03:06.545041Z","caller":"traceutil/trace.go:171","msg":"trace[578399480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4942; }","duration":"644.062737ms","start":"2026-04-08T13:03:05.90096Z","end":"2026-04-08T13:03:06.545023Z","steps":["trace[578399480] 'agreement among raft nodes before linearized reading' (duration: 643.849318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.545085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:05.900942Z","time spent":"644.134341ms","remote":"127.0.0.1:43282","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":"info","ts":"2026-04-08T13:03:06.545134Z","caller":"traceutil/trace.go:171","msg":"trace[1143538978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4942; }","duration":"337.08593ms","start":"2026-04-08T13:03:06.20798Z","end":"2026-04-08T13:03:06.545066Z","steps":["trace[1143538978] 'agreement among raft nodes before linearized reading' (duration: 336.826528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.545156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.843036ms","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":"warn","ts":"2026-04-08T13:03:06.545219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:06.207968Z","time spent":"337.236348ms","remote":"127.0.0.1:43282","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-08T13:03:06.54525Z","caller":"traceutil/trace.go:171","msg":"trace[462972041] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:4942; }","duration":"279.953421ms","start":"2026-04-08T13:03:06.265272Z","end":"2026-04-08T13:03:06.545225Z","steps":["trace[462972041] 'agreement among raft nodes before linearized reading' (duration: 279.7276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.545349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.576811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-04-08T13:03:06.545464Z","caller":"traceutil/trace.go:171","msg":"trace[1966719156] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4942; }","duration":"409.737688ms","start":"2026-04-08T13:03:06.135714Z","end":"2026-04-08T13:03:06.545452Z","steps":["trace[1966719156] 'agreement among raft nodes before linearized reading' (duration: 409.479766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.54553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.028091ms","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":"warn","ts":"2026-04-08T13:03:06.545501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:06.135688Z","time spent":"409.803131ms","remote":"127.0.0.1:43212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-08T13:03:06.545574Z","caller":"traceutil/trace.go:171","msg":"trace[2058690429] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4942; }","duration":"223.117716ms","start":"2026-04-08T13:03:06.322444Z","end":"2026-04-08T13:03:06.545561Z","steps":["trace[2058690429] 'agreement among raft nodes before linearized reading' (duration: 223.056783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.545691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.32192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-08T13:03:06.545917Z","caller":"traceutil/trace.go:171","msg":"trace[538104375] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4942; }","duration":"296.602793ms","start":"2026-04-08T13:03:06.2493Z","end":"2026-04-08T13:03:06.545903Z","steps":["trace[538104375] 'agreement among raft nodes before linearized reading' (duration: 296.243836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.545983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.946455ms","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-08T13:03:06.54604Z","caller":"traceutil/trace.go:171","msg":"trace[1805854549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4942; }","duration":"342.04707ms","start":"2026-04-08T13:03:06.203982Z","end":"2026-04-08T13:03:06.546029Z","steps":["trace[1805854549] 'agreement among raft nodes before linearized reading' (duration: 341.869422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.546054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.914961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-04-08T13:03:06.546072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:06.203968Z","time spent":"342.095522ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T13:03:06.546109Z","caller":"traceutil/trace.go:171","msg":"trace[1108862501] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4942; }","duration":"373.017866ms","start":"2026-04-08T13:03:06.173078Z","end":"2026-04-08T13:03:06.546096Z","steps":["trace[1108862501] 'agreement among raft nodes before linearized reading' (duration: 371.850152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.54615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:06.173057Z","time spent":"373.082689ms","remote":"127.0.0.1:43188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-08T13:03:06.545793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.382833ms","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-08T13:03:06.546278Z","caller":"traceutil/trace.go:171","msg":"trace[1983073099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4942; }","duration":"453.891696ms","start":"2026-04-08T13:03:06.092371Z","end":"2026-04-08T13:03:06.546263Z","steps":["trace[1983073099] 'agreement among raft nodes before linearized reading' (duration: 453.367511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.546399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:06.092354Z","time spent":"453.9952ms","remote":"127.0.0.1:43282","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-08T13:03:06.545833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.688758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-08T13:03:06.546651Z","caller":"traceutil/trace.go:171","msg":"trace[1190160765] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4942; }","duration":"335.524087ms","start":"2026-04-08T13:03:06.211115Z","end":"2026-04-08T13:03:06.546639Z","steps":["trace[1190160765] 'agreement among raft nodes before linearized reading' (duration: 334.622975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.54586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.30641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-08T13:03:06.546932Z","caller":"traceutil/trace.go:171","msg":"trace[942309610] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4942; }","duration":"539.41049ms","start":"2026-04-08T13:03:06.007504Z","end":"2026-04-08T13:03:06.546914Z","steps":["trace[942309610] 'agreement among raft nodes before linearized reading' (duration: 538.30329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.545822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.432542ms","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-08T13:03:06.546991Z","caller":"traceutil/trace.go:171","msg":"trace[828181064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4942; }","duration":"342.634687ms","start":"2026-04-08T13:03:06.204338Z","end":"2026-04-08T13:03:06.546973Z","steps":["trace[828181064] 'agreement among raft nodes before linearized reading' (duration: 341.348878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.547018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:06.007476Z","time spent":"539.529137ms","remote":"127.0.0.1:46022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-08T13:03:06.547067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:06.204324Z","time spent":"342.718872ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T13:03:06.546856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:06.211106Z","time spent":"335.736486ms","remote":"127.0.0.1:43282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-08T13:03:06.704344Z","caller":"traceutil/trace.go:171","msg":"trace[826007915] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"151.342577ms","start":"2026-04-08T13:03:06.552966Z","end":"2026-04-08T13:03:06.704309Z","steps":["trace[826007915] 'process raft request' (duration: 151.048784ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:06.704574Z","caller":"traceutil/trace.go:171","msg":"trace[2041221094] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"151.224872ms","start":"2026-04-08T13:03:06.553338Z","end":"2026-04-08T13:03:06.704563Z","steps":["trace[2041221094] 'process raft request' (duration: 151.073675ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:06.704574Z","caller":"traceutil/trace.go:171","msg":"trace[986497645] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"151.245273ms","start":"2026-04-08T13:03:06.553303Z","end":"2026-04-08T13:03:06.704549Z","steps":["trace[986497645] 'process raft request' (duration: 151.077795ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:06.704501Z","caller":"traceutil/trace.go:171","msg":"trace[1840181889] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"151.276184ms","start":"2026-04-08T13:03:06.553209Z","end":"2026-04-08T13:03:06.704485Z","steps":["trace[1840181889] 'process raft request' (duration: 150.978261ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:06.704532Z","caller":"traceutil/trace.go:171","msg":"trace[1971583582] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"151.298695ms","start":"2026-04-08T13:03:06.553226Z","end":"2026-04-08T13:03:06.704525Z","steps":["trace[1971583582] 'process raft request' (duration: 151.086375ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:06.704734Z","caller":"traceutil/trace.go:171","msg":"trace[2097295968] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"147.149783ms","start":"2026-04-08T13:03:06.557556Z","end":"2026-04-08T13:03:06.704705Z","steps":["trace[2097295968] 'process raft request' (duration: 146.882661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:06.704972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.939324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f37aef6e-1374-4c17-aa4c-9cc927bd91bf\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-08T13:03:06.70505Z","caller":"traceutil/trace.go:171","msg":"trace[1338616743] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f37aef6e-1374-4c17-aa4c-9cc927bd91bf; range_end:; response_count:1; response_revision:4949; }","duration":"152.06286ms","start":"2026-04-08T13:03:06.55297Z","end":"2026-04-08T13:03:06.705033Z","steps":["trace[1338616743] 'agreement among raft nodes before linearized reading' (duration: 151.84273ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:06.704606Z","caller":"traceutil/trace.go:171","msg":"trace[33820991] linearizableReadLoop","detail":"{readStateIndex:5135; appliedIndex:5129; }","duration":"119.614897ms","start":"2026-04-08T13:03:06.584966Z","end":"2026-04-08T13:03:06.704581Z","steps":["trace[33820991] 'read index received' (duration: 119.02649ms)","trace[33820991] 'applied index is now lower than readState.Index' (duration: 587.247µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:03:06.705629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.977462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-04-08T13:03:06.705699Z","caller":"traceutil/trace.go:171","msg":"trace[1613375431] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4949; }","duration":"115.099057ms","start":"2026-04-08T13:03:06.590583Z","end":"2026-04-08T13:03:06.705682Z","steps":["trace[1613375431] 'agreement among raft nodes before linearized reading' (duration: 114.876307ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:06.921213Z","caller":"traceutil/trace.go:171","msg":"trace[1498375507] linearizableReadLoop","detail":"{readStateIndex:5141; appliedIndex:5140; }","duration":"141.322063ms","start":"2026-04-08T13:03:06.779863Z","end":"2026-04-08T13:03:06.921185Z","steps":["trace[1498375507] 'read index received' (duration: 52.115396ms)","trace[1498375507] 'applied index is now lower than readState.Index' (duration: 89.205307ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:03:06.921425Z","caller":"traceutil/trace.go:171","msg":"trace[867500238] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"144.458408ms","start":"2026-04-08T13:03:06.776936Z","end":"2026-04-08T13:03:06.921394Z","steps":["trace[867500238] 'process raft request' (duration: 54.984819ms)","trace[867500238] 'compare' (duration: 88.979717ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:03:06.92146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.579454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-08T13:03:06.921519Z","caller":"traceutil/trace.go:171","msg":"trace[1395751146] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4955; }","duration":"141.665948ms","start":"2026-04-08T13:03:06.77983Z","end":"2026-04-08T13:03:06.921495Z","steps":["trace[1395751146] 'agreement among raft nodes before linearized reading' (duration: 141.463389ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:50.943121Z","caller":"traceutil/trace.go:171","msg":"trace[1625568639] linearizableReadLoop","detail":"{readStateIndex:5530; appliedIndex:5529; }","duration":"121.446538ms","start":"2026-04-08T13:03:50.821656Z","end":"2026-04-08T13:03:50.943103Z","steps":["trace[1625568639] 'read index received' (duration: 121.273619ms)","trace[1625568639] 'applied index is now lower than readState.Index' (duration: 172.299µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:03:50.943366Z","caller":"traceutil/trace.go:171","msg":"trace[943950369] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"147.705329ms","start":"2026-04-08T13:03:50.795652Z","end":"2026-04-08T13:03:50.943358Z","steps":["trace[943950369] 'process raft request' (duration: 147.3383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:50.943648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.882589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:03:50.943724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.813925ms","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-08T13:03:50.943756Z","caller":"traceutil/trace.go:171","msg":"trace[280916894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5334; }","duration":"111.877098ms","start":"2026-04-08T13:03:50.831871Z","end":"2026-04-08T13:03:50.943748Z","steps":["trace[280916894] 'agreement among raft nodes before linearized reading' (duration: 111.803194ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:50.943727Z","caller":"traceutil/trace.go:171","msg":"trace[1616753046] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5334; }","duration":"117.035548ms","start":"2026-04-08T13:03:50.826673Z","end":"2026-04-08T13:03:50.943708Z","steps":["trace[1616753046] 'agreement among raft nodes before linearized reading' (duration: 116.766884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:50.943899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.086101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-04-08T13:03:50.944147Z","caller":"traceutil/trace.go:171","msg":"trace[469514113] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5334; }","duration":"113.364476ms","start":"2026-04-08T13:03:50.830776Z","end":"2026-04-08T13:03:50.94414Z","steps":["trace[469514113] 'agreement among raft nodes before linearized reading' (duration: 113.089361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:50.944463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.786578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:03:50.944556Z","caller":"traceutil/trace.go:171","msg":"trace[1494370531] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5334; }","duration":"122.971838ms","start":"2026-04-08T13:03:50.821561Z","end":"2026-04-08T13:03:50.944533Z","steps":["trace[1494370531] 'agreement among raft nodes before linearized reading' (duration: 121.700331ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:18.203191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2912} {"level":"warn","ts":"2026-04-08T13:04:18.419986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.728877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:04:18.420175Z","caller":"traceutil/trace.go:171","msg":"trace[1049262217] transaction","detail":"{read_only:false; response_revision:5557; number_of_response:1; }","duration":"140.250521ms","start":"2026-04-08T13:04:18.279832Z","end":"2026-04-08T13:04:18.420083Z","steps":["trace[1049262217] 'process raft request' (duration: 27.344354ms)","trace[1049262217] 'compare' (duration: 56.869886ms)","trace[1049262217] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; req_size:531; } (duration: 55.592297ms)"],"step_count":3} {"level":"info","ts":"2026-04-08T13:04:18.504372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2912,"took":"300.085767ms","hash":168881646,"current-db-size-bytes":52588544,"current-db-size":"53 MB","current-db-size-in-use-bytes":29470720,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-08T13:04:18.504436Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":168881646,"revision":2912,"compact-revision":-1} {"level":"info","ts":"2026-04-08T13:06:30.511874Z","caller":"traceutil/trace.go:171","msg":"trace[1238096577] transaction","detail":"{read_only:false; response_revision:6433; number_of_response:1; }","duration":"109.301337ms","start":"2026-04-08T13:06:30.402545Z","end":"2026-04-08T13:06:30.511847Z","steps":["trace[1238096577] 'process raft request' (duration: 109.02646ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:06:53.664306Z","caller":"traceutil/trace.go:171","msg":"trace[712371183] transaction","detail":"{read_only:false; response_revision:6601; number_of_response:1; }","duration":"181.169324ms","start":"2026-04-08T13:06:53.483066Z","end":"2026-04-08T13:06:53.664235Z","steps":["trace[712371183] 'process raft request' (duration: 180.987053ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:07:19.234548Z","caller":"traceutil/trace.go:171","msg":"trace[893942308] linearizableReadLoop","detail":"{readStateIndex:7035; appliedIndex:7034; }","duration":"128.064682ms","start":"2026-04-08T13:07:19.10646Z","end":"2026-04-08T13:07:19.234524Z","steps":["trace[893942308] 'read index received' (duration: 127.790604ms)","trace[893942308] 'applied index is now lower than readState.Index' (duration: 273.248µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:07:19.234752Z","caller":"traceutil/trace.go:171","msg":"trace[1670368942] transaction","detail":"{read_only:false; response_revision:6787; number_of_response:1; }","duration":"137.659941ms","start":"2026-04-08T13:07:19.097024Z","end":"2026-04-08T13:07:19.234684Z","steps":["trace[1670368942] 'process raft request' (duration: 137.344291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:07:19.235334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.841971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:25 size:436416"} {"level":"info","ts":"2026-04-08T13:07:19.235377Z","caller":"traceutil/trace.go:171","msg":"trace[1320977246] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:25; response_revision:6787; }","duration":"128.934087ms","start":"2026-04-08T13:07:19.106435Z","end":"2026-04-08T13:07:19.235369Z","steps":["trace[1320977246] 'agreement among raft nodes before linearized reading' (duration: 128.417464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:06.513184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.862517ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-08T13:08:08.883837Z","caller":"traceutil/trace.go:171","msg":"trace[478345784] linearizableReadLoop","detail":"{readStateIndex:7320; appliedIndex:7319; }","duration":"120.651752ms","start":"2026-04-08T13:08:08.763166Z","end":"2026-04-08T13:08:08.883817Z","steps":["trace[478345784] 'read index received' (duration: 58.50415ms)","trace[478345784] 'applied index is now lower than readState.Index' (duration: 62.146182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:08:08.884284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.06993ms","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-08T13:08:08.884345Z","caller":"traceutil/trace.go:171","msg":"trace[487529494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7062; }","duration":"121.189198ms","start":"2026-04-08T13:08:08.763141Z","end":"2026-04-08T13:08:08.884331Z","steps":["trace[487529494] 'agreement among raft nodes before linearized reading' (duration: 120.985773ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:08.884542Z","caller":"traceutil/trace.go:171","msg":"trace[715871262] transaction","detail":"{read_only:false; response_revision:7062; number_of_response:1; }","duration":"124.993237ms","start":"2026-04-08T13:08:08.759535Z","end":"2026-04-08T13:08:08.884528Z","steps":["trace[715871262] 'process raft request' (duration: 62.186403ms)","trace[715871262] 'compare' (duration: 61.920178ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:08:08.884981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.418165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T13:08:08.885048Z","caller":"traceutil/trace.go:171","msg":"trace[1528710549] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7062; }","duration":"101.487083ms","start":"2026-04-08T13:08:08.783549Z","end":"2026-04-08T13:08:08.885036Z","steps":["trace[1528710549] 'agreement among raft nodes before linearized reading' (duration: 101.353247ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:09.011559Z","caller":"traceutil/trace.go:171","msg":"trace[96478466] transaction","detail":"{read_only:false; response_revision:7063; number_of_response:1; }","duration":"122.829386ms","start":"2026-04-08T13:08:08.888699Z","end":"2026-04-08T13:08:09.011528Z","steps":["trace[96478466] 'process raft request' (duration: 101.363927ms)","trace[96478466] 'compare' (duration: 21.277674ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:09.011828Z","caller":"traceutil/trace.go:171","msg":"trace[1149866197] transaction","detail":"{read_only:false; response_revision:7064; number_of_response:1; }","duration":"122.435516ms","start":"2026-04-08T13:08:08.88937Z","end":"2026-04-08T13:08:09.011806Z","steps":["trace[1149866197] 'process raft request' (duration: 122.101065ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:09.012343Z","caller":"traceutil/trace.go:171","msg":"trace[963114856] transaction","detail":"{read_only:false; response_revision:7065; number_of_response:1; }","duration":"122.915654ms","start":"2026-04-08T13:08:08.889412Z","end":"2026-04-08T13:08:09.012328Z","steps":["trace[963114856] 'process raft request' (duration: 122.327939ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:09.012869Z","caller":"traceutil/trace.go:171","msg":"trace[1963140379] transaction","detail":"{read_only:false; response_revision:7066; number_of_response:1; }","duration":"123.335843ms","start":"2026-04-08T13:08:08.889517Z","end":"2026-04-08T13:08:09.012853Z","steps":["trace[1963140379] 'process raft request' (duration: 122.71551ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:09.013311Z","caller":"traceutil/trace.go:171","msg":"trace[1146412451] transaction","detail":"{read_only:false; response_revision:7067; number_of_response:1; }","duration":"123.073041ms","start":"2026-04-08T13:08:08.890219Z","end":"2026-04-08T13:08:09.013292Z","steps":["trace[1146412451] 'process raft request' (duration: 122.577924ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:41.748061Z","caller":"traceutil/trace.go:171","msg":"trace[791593476] transaction","detail":"{read_only:false; response_revision:7231; number_of_response:1; }","duration":"115.301ms","start":"2026-04-08T13:08:41.632702Z","end":"2026-04-08T13:08:41.748003Z","steps":["trace[791593476] 'process raft request' (duration: 115.008715ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:05.668728Z","caller":"traceutil/trace.go:171","msg":"trace[976703252] transaction","detail":"{read_only:false; response_revision:7347; number_of_response:1; }","duration":"175.21373ms","start":"2026-04-08T13:09:05.493487Z","end":"2026-04-08T13:09:05.6687Z","steps":["trace[976703252] 'process raft request' (duration: 175.055772ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:05.674459Z","caller":"traceutil/trace.go:171","msg":"trace[371442122] transaction","detail":"{read_only:false; response_revision:7348; number_of_response:1; }","duration":"179.465636ms","start":"2026-04-08T13:09:05.494968Z","end":"2026-04-08T13:09:05.674434Z","steps":["trace[371442122] 'process raft request' (duration: 179.299208ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:05.674616Z","caller":"traceutil/trace.go:171","msg":"trace[1118179016] linearizableReadLoop","detail":"{readStateIndex:7618; appliedIndex:7615; }","duration":"166.973006ms","start":"2026-04-08T13:09:05.507616Z","end":"2026-04-08T13:09:05.674589Z","steps":["trace[1118179016] 'read index received' (duration: 160.971887ms)","trace[1118179016] 'applied index is now lower than readState.Index' (duration: 5.999219ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:05.674679Z","caller":"traceutil/trace.go:171","msg":"trace[1518978087] transaction","detail":"{read_only:false; response_revision:7349; number_of_response:1; }","duration":"178.937752ms","start":"2026-04-08T13:09:05.495712Z","end":"2026-04-08T13:09:05.674649Z","steps":["trace[1518978087] 'process raft request' (duration: 178.683735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:05.674832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.528878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T13:09:05.674864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.241542ms","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-08T13:09:05.674883Z","caller":"traceutil/trace.go:171","msg":"trace[2076852622] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:7349; }","duration":"133.597358ms","start":"2026-04-08T13:09:05.541273Z","end":"2026-04-08T13:09:05.67487Z","steps":["trace[2076852622] 'agreement among raft nodes before linearized reading' (duration: 133.462939ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:05.674922Z","caller":"traceutil/trace.go:171","msg":"trace[1387490818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7349; }","duration":"167.349411ms","start":"2026-04-08T13:09:05.507557Z","end":"2026-04-08T13:09:05.674907Z","steps":["trace[1387490818] 'agreement among raft nodes before linearized reading' (duration: 167.175453ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:18.209115Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5556} {"level":"info","ts":"2026-04-08T13:09:18.284775Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5556,"took":"74.406188ms","hash":610385398,"current-db-size-bytes":52588544,"current-db-size":"53 MB","current-db-size-in-use-bytes":18518016,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-08T13:09:18.28483Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":610385398,"revision":5556,"compact-revision":2912} {"level":"info","ts":"2026-04-08T13:10:07.122145Z","caller":"traceutil/trace.go:171","msg":"trace[1547893626] linearizableReadLoop","detail":"{readStateIndex:7941; appliedIndex:7940; }","duration":"172.137543ms","start":"2026-04-08T13:10:06.949983Z","end":"2026-04-08T13:10:07.12212Z","steps":["trace[1547893626] 'read index received' (duration: 171.788702ms)","trace[1547893626] 'applied index is now lower than readState.Index' (duration: 347.961µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:10:07.122241Z","caller":"traceutil/trace.go:171","msg":"trace[1407562649] transaction","detail":"{read_only:false; response_revision:7658; number_of_response:1; }","duration":"187.230628ms","start":"2026-04-08T13:10:06.934973Z","end":"2026-04-08T13:10:07.122204Z","steps":["trace[1407562649] 'process raft request' (duration: 186.984678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:10:07.122586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.763999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/keystone-keystone-db-init\" ","response":"range_response_count:1 size:752"} {"level":"info","ts":"2026-04-08T13:10:07.122666Z","caller":"traceutil/trace.go:171","msg":"trace[1513408659] range","detail":"{range_begin:/registry/rolebindings/openstack/keystone-keystone-db-init; range_end:; response_count:1; response_revision:7658; }","duration":"170.873029ms","start":"2026-04-08T13:10:06.951775Z","end":"2026-04-08T13:10:07.122648Z","steps":["trace[1513408659] 'agreement among raft nodes before linearized reading' (duration: 170.530057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:10:07.123068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.069517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/keystone-keystone-db-sync\" ","response":"range_response_count:1 size:752"} {"level":"info","ts":"2026-04-08T13:10:07.123111Z","caller":"traceutil/trace.go:171","msg":"trace[1824580763] range","detail":"{range_begin:/registry/rolebindings/openstack/keystone-keystone-db-sync; range_end:; response_count:1; response_revision:7658; }","duration":"173.152288ms","start":"2026-04-08T13:10:06.949949Z","end":"2026-04-08T13:10:07.123101Z","steps":["trace[1824580763] 'agreement among raft nodes before linearized reading' (duration: 172.961567ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:10:20.392443Z","caller":"traceutil/trace.go:171","msg":"trace[1677471241] transaction","detail":"{read_only:false; response_revision:7726; number_of_response:1; }","duration":"141.395568ms","start":"2026-04-08T13:10:20.251027Z","end":"2026-04-08T13:10:20.392423Z","steps":["trace[1677471241] 'process raft request' (duration: 136.474046ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:10:20.392582Z","caller":"traceutil/trace.go:171","msg":"trace[1950090610] linearizableReadLoop","detail":"{readStateIndex:8012; appliedIndex:8009; }","duration":"131.436973ms","start":"2026-04-08T13:10:20.261126Z","end":"2026-04-08T13:10:20.392563Z","steps":["trace[1950090610] 'read index received' (duration: 80.799208ms)","trace[1950090610] 'applied index is now lower than readState.Index' (duration: 50.637095ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:10:20.392731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.595444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-zgq2z\" ","response":"range_response_count:1 size:7124"} {"level":"info","ts":"2026-04-08T13:10:20.392757Z","caller":"traceutil/trace.go:171","msg":"trace[196187135] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-zgq2z; range_end:; response_count:1; response_revision:7727; }","duration":"131.654194ms","start":"2026-04-08T13:10:20.261096Z","end":"2026-04-08T13:10:20.39275Z","steps":["trace[196187135] 'agreement among raft nodes before linearized reading' (duration: 131.534093ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:10:20.392852Z","caller":"traceutil/trace.go:171","msg":"trace[289151259] transaction","detail":"{read_only:false; response_revision:7727; number_of_response:1; }","duration":"141.474647ms","start":"2026-04-08T13:10:20.25137Z","end":"2026-04-08T13:10:20.392844Z","steps":["trace[289151259] 'process raft request' (duration: 141.003325ms)"],"step_count":1}