{"level":"warn","ts":"2026-04-27T11:57:15.111453Z","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-27T11:57:15.111638Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.102:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.102:2380","--initial-cluster=instance=https://199.19.213.102:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.102:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.102: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-27T11:57:15.1119Z","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-27T11:57:15.111928Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.102:2380"]} {"level":"info","ts":"2026-04-27T11:57:15.111969Z","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-27T11:57:15.113885Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"]} {"level":"info","ts":"2026-04-27T11:57:15.114215Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.102:2380"],"listen-peer-urls":["https://199.19.213.102:2380"],"advertise-client-urls":["https://199.19.213.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.102: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-27T11:57:15.119075Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.467545ms"} {"level":"info","ts":"2026-04-27T11:57:15.13113Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1ee87564e61d159f","cluster-id":"92a92aec402e97c3"} {"level":"info","ts":"2026-04-27T11:57:15.131255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T11:57:15.131301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became follower at term 0"} {"level":"info","ts":"2026-04-27T11:57:15.131326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1ee87564e61d159f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T11:57:15.131348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became follower at term 1"} {"level":"info","ts":"2026-04-27T11:57:15.131444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=(2227159091952555423)"} {"level":"warn","ts":"2026-04-27T11:57:15.143111Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T11:57:15.148117Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T11:57:15.149173Z","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-27T11:57:15.151279Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1ee87564e61d159f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T11:57:15.15155Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1ee87564e61d159f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T11:57:15.151694Z","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-27T11:57:15.151726Z","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-27T11:57:15.151736Z","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-27T11:57:15.152201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f switched to configuration voters=(2227159091952555423)"} {"level":"info","ts":"2026-04-27T11:57:15.152316Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92a92aec402e97c3","local-member-id":"1ee87564e61d159f","added-peer-id":"1ee87564e61d159f","added-peer-peer-urls":["https://199.19.213.102:2380"]} {"level":"info","ts":"2026-04-27T11:57:15.156043Z","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-27T11:57:15.156253Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.102:2380"} {"level":"info","ts":"2026-04-27T11:57:15.156258Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1ee87564e61d159f","initial-advertise-peer-urls":["https://199.19.213.102:2380"],"listen-peer-urls":["https://199.19.213.102:2380"],"advertise-client-urls":["https://199.19.213.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T11:57:15.156318Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T11:57:15.156314Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.102:2380"} {"level":"info","ts":"2026-04-27T11:57:15.732709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T11:57:15.732805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T11:57:15.732875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f received MsgPreVoteResp from 1ee87564e61d159f at term 1"} {"level":"info","ts":"2026-04-27T11:57:15.7329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became candidate at term 2"} {"level":"info","ts":"2026-04-27T11:57:15.73291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f received MsgVoteResp from 1ee87564e61d159f at term 2"} {"level":"info","ts":"2026-04-27T11:57:15.732928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee87564e61d159f became leader at term 2"} {"level":"info","ts":"2026-04-27T11:57:15.732941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1ee87564e61d159f elected leader 1ee87564e61d159f at term 2"} {"level":"info","ts":"2026-04-27T11:57:15.734321Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1ee87564e61d159f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.102:2379]}","request-path":"/0/members/1ee87564e61d159f/attributes","cluster-id":"92a92aec402e97c3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T11:57:15.734536Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T11:57:15.734596Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T11:57:15.735207Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:57:15.735424Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T11:57:15.735462Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T11:57:15.738298Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T11:57:15.738279Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92a92aec402e97c3","local-member-id":"1ee87564e61d159f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:57:15.739204Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.102:2379"} {"level":"info","ts":"2026-04-27T11:57:15.760887Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:57:15.761013Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T11:57:33.76551Z","caller":"traceutil/trace.go:171","msg":"trace[500958856] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"248.522352ms","start":"2026-04-27T11:57:33.516959Z","end":"2026-04-27T11:57:33.765482Z","steps":["trace[500958856] 'process raft request' (duration: 248.25979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:57:34.235996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.754953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T11:57:34.236113Z","caller":"traceutil/trace.go:171","msg":"trace[419069440] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"413.24858ms","start":"2026-04-27T11:57:33.822848Z","end":"2026-04-27T11:57:34.236097Z","steps":["trace[419069440] 'read index received' (duration: 287.843061ms)","trace[419069440] 'applied index is now lower than readState.Index' (duration: 125.404549ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:57:34.236177Z","caller":"traceutil/trace.go:171","msg":"trace[216095517] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"466.41641ms","start":"2026-04-27T11:57:33.769751Z","end":"2026-04-27T11:57:34.236167Z","steps":["trace[216095517] 'process raft request' (duration: 340.944071ms)","trace[216095517] 'compare' (duration: 124.626292ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:57:34.236236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:57:33.76973Z","time spent":"466.47701ms","remote":"127.0.0.1:45212","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-27T11:57:34.236463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.552812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T11:57:34.236551Z","caller":"traceutil/trace.go:171","msg":"trace[1150505891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:445; }","duration":"413.736123ms","start":"2026-04-27T11:57:33.82279Z","end":"2026-04-27T11:57:34.236526Z","steps":["trace[1150505891] 'agreement among raft nodes before linearized reading' (duration: 413.522722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:57:34.236601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:57:33.822769Z","time spent":"413.823354ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T11:57:34.380684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.870579ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T11:57:34.380956Z","caller":"traceutil/trace.go:171","msg":"trace[1331397669] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"144.78913ms","start":"2026-04-27T11:57:34.236138Z","end":"2026-04-27T11:57:34.380927Z","steps":["trace[1331397669] 'read index received' (duration: 7.69864ms)","trace[1331397669] 'applied index is now lower than readState.Index' (duration: 137.08712ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:57:34.381093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.91094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T11:57:34.381092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.021208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T11:57:34.381148Z","caller":"traceutil/trace.go:171","msg":"trace[2096013602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:446; }","duration":"258.995961ms","start":"2026-04-27T11:57:34.122138Z","end":"2026-04-27T11:57:34.381134Z","steps":["trace[2096013602] 'agreement among raft nodes before linearized reading' (duration: 258.88129ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:57:34.381159Z","caller":"traceutil/trace.go:171","msg":"trace[49154134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:446; }","duration":"169.125858ms","start":"2026-04-27T11:57:34.212019Z","end":"2026-04-27T11:57:34.381145Z","steps":["trace[49154134] 'agreement among raft nodes before linearized reading' (duration: 169.002967ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:57:34.381449Z","caller":"traceutil/trace.go:171","msg":"trace[1223586030] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"140.254975ms","start":"2026-04-27T11:57:34.241178Z","end":"2026-04-27T11:57:34.381433Z","steps":["trace[1223586030] 'process raft request' (duration: 139.65238ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:57:42.549865Z","caller":"traceutil/trace.go:171","msg":"trace[2059316418] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"130.734577ms","start":"2026-04-27T11:57:42.419102Z","end":"2026-04-27T11:57:42.549837Z","steps":["trace[2059316418] 'read index received' (duration: 130.598246ms)","trace[2059316418] 'applied index is now lower than readState.Index' (duration: 135.361µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:57:42.54995Z","caller":"traceutil/trace.go:171","msg":"trace[816748916] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"273.54853ms","start":"2026-04-27T11:57:42.27636Z","end":"2026-04-27T11:57:42.549908Z","steps":["trace[816748916] 'process raft request' (duration: 273.327459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:57:42.550049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.943989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-27T11:57:42.55004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.914189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-27T11:57:42.550101Z","caller":"traceutil/trace.go:171","msg":"trace[1376034956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:466; }","duration":"131.021409ms","start":"2026-04-27T11:57:42.41906Z","end":"2026-04-27T11:57:42.550081Z","steps":["trace[1376034956] 'agreement among raft nodes before linearized reading' (duration: 130.925569ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:57:42.550108Z","caller":"traceutil/trace.go:171","msg":"trace[395900543] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:466; }","duration":"131.031079ms","start":"2026-04-27T11:57:42.41906Z","end":"2026-04-27T11:57:42.550091Z","steps":["trace[395900543] 'agreement among raft nodes before linearized reading' (duration: 130.892518ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:57:51.989432Z","caller":"traceutil/trace.go:171","msg":"trace[285822920] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"189.636365ms","start":"2026-04-27T11:57:51.799758Z","end":"2026-04-27T11:57:51.989394Z","steps":["trace[285822920] 'process raft request' (duration: 161.785781ms)","trace[285822920] 'compare' (duration: 27.618502ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:57:52.494726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.588954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T11:57:52.494826Z","caller":"traceutil/trace.go:171","msg":"trace[411770063] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"495.112089ms","start":"2026-04-27T11:57:51.999698Z","end":"2026-04-27T11:57:52.49481Z","steps":["trace[411770063] 'read index received' (duration: 119.180742ms)","trace[411770063] 'applied index is now lower than readState.Index' (duration: 375.930227ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:57:52.495081Z","caller":"traceutil/trace.go:171","msg":"trace[1265680504] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"501.993085ms","start":"2026-04-27T11:57:51.993042Z","end":"2026-04-27T11:57:52.495036Z","steps":["trace[1265680504] 'process raft request' (duration: 126.006778ms)","trace[1265680504] 'compare' (duration: 375.267892ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:57:52.495157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.425691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T11:57:52.495205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.069496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ql7mx\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-27T11:57:52.495248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:57:51.993024Z","time spent":"502.138766ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:57:52.495258Z","caller":"traceutil/trace.go:171","msg":"trace[1583793206] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ql7mx; range_end:; response_count:1; response_revision:616; }","duration":"487.180896ms","start":"2026-04-27T11:57:52.00806Z","end":"2026-04-27T11:57:52.495241Z","steps":["trace[1583793206] 'agreement among raft nodes before linearized reading' (duration: 486.993455ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:57:52.495268Z","caller":"traceutil/trace.go:171","msg":"trace[1968220873] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:616; }","duration":"495.587912ms","start":"2026-04-27T11:57:51.999649Z","end":"2026-04-27T11:57:52.495237Z","steps":["trace[1968220873] 'agreement among raft nodes before linearized reading' (duration: 495.377651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:57:52.495302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:57:52.008036Z","time spent":"487.258146ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-ql7mx\" "} {"level":"warn","ts":"2026-04-27T11:57:52.49511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.561677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T11:57:52.49533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:57:51.999629Z","time spent":"495.686953ms","remote":"127.0.0.1:45184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-27T11:57:52.495378Z","caller":"traceutil/trace.go:171","msg":"trace[228775468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"371.874259ms","start":"2026-04-27T11:57:52.123489Z","end":"2026-04-27T11:57:52.495363Z","steps":["trace[228775468] 'agreement among raft nodes before linearized reading' (duration: 371.577727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:57:52.495445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:57:52.123462Z","time spent":"371.97179ms","remote":"127.0.0.1:44930","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-27T11:57:53.140458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:57:52.620562Z","time spent":"519.886843ms","remote":"127.0.0.1:55668","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-27T11:57:53.141512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.706326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T11:57:53.141696Z","caller":"traceutil/trace.go:171","msg":"trace[903819035] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"553.120271ms","start":"2026-04-27T11:57:52.588523Z","end":"2026-04-27T11:57:53.141643Z","steps":["trace[903819035] 'read index received' (duration: 346.977465ms)","trace[903819035] 'applied index is now lower than readState.Index' (duration: 206.141596ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:57:53.141753Z","caller":"traceutil/trace.go:171","msg":"trace[1922834863] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"632.009779ms","start":"2026-04-27T11:57:52.509685Z","end":"2026-04-27T11:57:53.141694Z","steps":["trace[1922834863] 'process raft request' (duration: 427.013401ms)","trace[1922834863] 'compare' (duration: 204.524014ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:57:53.141869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.348992ms","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-27T11:57:53.1419Z","caller":"traceutil/trace.go:171","msg":"trace[2071664481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"553.406363ms","start":"2026-04-27T11:57:52.588484Z","end":"2026-04-27T11:57:53.141891Z","steps":["trace[2071664481] 'agreement among raft nodes before linearized reading' (duration: 553.266513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:57:53.141875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:57:52.509666Z","time spent":"632.13109ms","remote":"127.0.0.1:45132","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-27T11:57:53.141929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:57:52.588464Z","time spent":"553.458844ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T11:57:53.370405Z","caller":"traceutil/trace.go:171","msg":"trace[967222204] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"228.655978ms","start":"2026-04-27T11:57:53.141724Z","end":"2026-04-27T11:57:53.37038Z","steps":["trace[967222204] 'read index received' (duration: 228.071475ms)","trace[967222204] 'applied index is now lower than readState.Index' (duration: 583.763µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:57:53.370722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.903931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T11:57:53.370824Z","caller":"traceutil/trace.go:171","msg":"trace[1406275924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"773.083971ms","start":"2026-04-27T11:57:52.597727Z","end":"2026-04-27T11:57:53.370811Z","steps":["trace[1406275924] 'agreement among raft nodes before linearized reading' (duration: 772.81408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:57:53.370904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:57:52.597708Z","time spent":"773.185483ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T11:57:53.371045Z","caller":"traceutil/trace.go:171","msg":"trace[989704835] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"484.581561ms","start":"2026-04-27T11:57:52.886418Z","end":"2026-04-27T11:57:53.371Z","steps":["trace[989704835] 'process raft request' (duration: 483.442643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:57:53.371203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:57:52.886394Z","time spent":"484.728931ms","remote":"127.0.0.1:45042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T11:57:53.371119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.672213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-27T11:57:53.371345Z","caller":"traceutil/trace.go:171","msg":"trace[734126561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"768.928734ms","start":"2026-04-27T11:57:52.602388Z","end":"2026-04-27T11:57:53.371317Z","steps":["trace[734126561] 'agreement among raft nodes before linearized reading' (duration: 768.31287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:57:53.371426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:57:52.602381Z","time spent":"769.017425ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T11:57:53.400488Z","caller":"traceutil/trace.go:171","msg":"trace[453422788] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"253.783262ms","start":"2026-04-27T11:57:53.146685Z","end":"2026-04-27T11:57:53.400468Z","steps":["trace[453422788] 'process raft request' (duration: 253.62744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:57:55.272332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.065929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:57:55.272391Z","caller":"traceutil/trace.go:171","msg":"trace[1162407924] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:651; }","duration":"134.166291ms","start":"2026-04-27T11:57:55.13821Z","end":"2026-04-27T11:57:55.272377Z","steps":["trace[1162407924] 'range keys from in-memory index tree' (duration: 133.992588ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:57:59.956444Z","caller":"traceutil/trace.go:171","msg":"trace[245875432] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"107.829363ms","start":"2026-04-27T11:57:59.848591Z","end":"2026-04-27T11:57:59.95642Z","steps":["trace[245875432] 'process raft request' (duration: 107.667802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:57:59.956701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.838909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:57:59.956748Z","caller":"traceutil/trace.go:171","msg":"trace[1490952706] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:713; }","duration":"108.90152ms","start":"2026-04-27T11:57:59.847835Z","end":"2026-04-27T11:57:59.956737Z","steps":["trace[1490952706] 'agreement among raft nodes before linearized reading' (duration: 108.827319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:57:59.956779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.415306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:57:59.956869Z","caller":"traceutil/trace.go:171","msg":"trace[88541866] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:713; }","duration":"108.584247ms","start":"2026-04-27T11:57:59.848262Z","end":"2026-04-27T11:57:59.956846Z","steps":["trace[88541866] 'agreement among raft nodes before linearized reading' (duration: 108.415996ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:58:10.531521Z","caller":"traceutil/trace.go:171","msg":"trace[868695465] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"407.183486ms","start":"2026-04-27T11:58:10.124319Z","end":"2026-04-27T11:58:10.531502Z","steps":["trace[868695465] 'process raft request' (duration: 407.045565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:58:10.53165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:58:10.124297Z","time spent":"407.291497ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:58:28.286774Z","caller":"traceutil/trace.go:171","msg":"trace[95052644] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"110.777739ms","start":"2026-04-27T11:58:28.175969Z","end":"2026-04-27T11:58:28.286747Z","steps":["trace[95052644] 'process raft request' (duration: 110.449487ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:58:28.286912Z","caller":"traceutil/trace.go:171","msg":"trace[1385110137] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"101.127416ms","start":"2026-04-27T11:58:28.185759Z","end":"2026-04-27T11:58:28.286886Z","steps":["trace[1385110137] 'process raft request' (duration: 100.932289ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:58:28.287049Z","caller":"traceutil/trace.go:171","msg":"trace[86919495] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"101.24351ms","start":"2026-04-27T11:58:28.185772Z","end":"2026-04-27T11:58:28.287016Z","steps":["trace[86919495] 'process raft request' (duration: 100.988841ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:58:28.286963Z","caller":"traceutil/trace.go:171","msg":"trace[1697449913] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"108.168734ms","start":"2026-04-27T11:58:28.178702Z","end":"2026-04-27T11:58:28.286871Z","steps":["trace[1697449913] 'process raft request' (duration: 107.916585ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:58:28.287218Z","caller":"traceutil/trace.go:171","msg":"trace[1619448823] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"100.882037ms","start":"2026-04-27T11:58:28.186319Z","end":"2026-04-27T11:58:28.287202Z","steps":["trace[1619448823] 'process raft request' (duration: 100.801404ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:58:28.331834Z","caller":"traceutil/trace.go:171","msg":"trace[1395225069] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"113.743327ms","start":"2026-04-27T11:58:28.21807Z","end":"2026-04-27T11:58:28.331813Z","steps":["trace[1395225069] 'process raft request' (duration: 113.689685ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:58:28.331919Z","caller":"traceutil/trace.go:171","msg":"trace[108923378] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"114.893849ms","start":"2026-04-27T11:58:28.216998Z","end":"2026-04-27T11:58:28.331892Z","steps":["trace[108923378] 'process raft request' (duration: 114.66012ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:58:39.638729Z","caller":"traceutil/trace.go:171","msg":"trace[450513445] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"164.086439ms","start":"2026-04-27T11:58:39.474594Z","end":"2026-04-27T11:58:39.63868Z","steps":["trace[450513445] 'read index received' (duration: 163.739818ms)","trace[450513445] 'applied index is now lower than readState.Index' (duration: 345.111µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:58:39.638808Z","caller":"traceutil/trace.go:171","msg":"trace[626339909] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"301.103398ms","start":"2026-04-27T11:58:39.337669Z","end":"2026-04-27T11:58:39.638772Z","steps":["trace[626339909] 'process raft request' (duration: 300.712036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:58:39.638913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.295716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:58:39.638964Z","caller":"traceutil/trace.go:171","msg":"trace[1090666221] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1179; }","duration":"164.414709ms","start":"2026-04-27T11:58:39.474536Z","end":"2026-04-27T11:58:39.63895Z","steps":["trace[1090666221] 'agreement among raft nodes before linearized reading' (duration: 164.303176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:58:39.639011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:58:39.337652Z","time spent":"301.219493ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T11:58:39.74814Z","caller":"traceutil/trace.go:171","msg":"trace[170637880] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"225.514082ms","start":"2026-04-27T11:58:39.522594Z","end":"2026-04-27T11:58:39.748108Z","steps":["trace[170637880] 'process raft request' (duration: 224.741586ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:58:39.748387Z","caller":"traceutil/trace.go:171","msg":"trace[663816320] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"158.419977ms","start":"2026-04-27T11:58:39.589922Z","end":"2026-04-27T11:58:39.748342Z","steps":["trace[663816320] 'process raft request' (duration: 158.132108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:58:39.748855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.239958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-27T11:58:39.748933Z","caller":"traceutil/trace.go:171","msg":"trace[1558512987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1181; }","duration":"100.371243ms","start":"2026-04-27T11:58:39.648543Z","end":"2026-04-27T11:58:39.748914Z","steps":["trace[1558512987] 'agreement among raft nodes before linearized reading' (duration: 99.787494ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:58:58.562827Z","caller":"traceutil/trace.go:171","msg":"trace[1076414969] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"148.832157ms","start":"2026-04-27T11:58:58.41396Z","end":"2026-04-27T11:58:58.562792Z","steps":["trace[1076414969] 'process raft request' (duration: 94.040999ms)","trace[1076414969] 'compare' (duration: 54.671235ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:18.039848Z","caller":"traceutil/trace.go:171","msg":"trace[494477000] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"123.137446ms","start":"2026-04-27T11:59:17.916666Z","end":"2026-04-27T11:59:18.039804Z","steps":["trace[494477000] 'process raft request' (duration: 122.741938ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:18.040137Z","caller":"traceutil/trace.go:171","msg":"trace[1940804406] transaction","detail":"{read_only:false; response_revision:1707; number_of_response:1; }","duration":"122.137356ms","start":"2026-04-27T11:59:17.917968Z","end":"2026-04-27T11:59:18.040105Z","steps":["trace[1940804406] 'process raft request' (duration: 121.746356ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:18.040417Z","caller":"traceutil/trace.go:171","msg":"trace[1981310664] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"106.451878ms","start":"2026-04-27T11:59:17.933943Z","end":"2026-04-27T11:59:18.040395Z","steps":["trace[1981310664] 'process raft request' (duration: 106.002887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:18.040652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.295431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:18.041158Z","caller":"traceutil/trace.go:171","msg":"trace[259205354] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:0; response_revision:1708; }","duration":"130.524186ms","start":"2026-04-27T11:59:17.910224Z","end":"2026-04-27T11:59:18.040748Z","steps":["trace[259205354] 'agreement among raft nodes before linearized reading' (duration: 130.076457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:18.041163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.879167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/pxc-operator-7cff949c8b-blfjh\" ","response":"range_response_count:1 size:3255"} {"level":"info","ts":"2026-04-27T11:59:18.041239Z","caller":"traceutil/trace.go:171","msg":"trace[1777166161] range","detail":"{range_begin:/registry/pods/openstack/pxc-operator-7cff949c8b-blfjh; range_end:; response_count:1; response_revision:1708; }","duration":"133.01378ms","start":"2026-04-27T11:59:17.908206Z","end":"2026-04-27T11:59:18.04122Z","steps":["trace[1777166161] 'agreement among raft nodes before linearized reading' (duration: 132.09021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:18.041657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.814527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/pxc-operator\" ","response":"range_response_count:1 size:4010"} {"level":"info","ts":"2026-04-27T11:59:18.041741Z","caller":"traceutil/trace.go:171","msg":"trace[1952688940] range","detail":"{range_begin:/registry/deployments/openstack/pxc-operator; range_end:; response_count:1; response_revision:1708; }","duration":"133.97185ms","start":"2026-04-27T11:59:17.907749Z","end":"2026-04-27T11:59:18.041721Z","steps":["trace[1952688940] 'agreement among raft nodes before linearized reading' (duration: 132.59269ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:26.898269Z","caller":"traceutil/trace.go:171","msg":"trace[444214184] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"127.147574ms","start":"2026-04-27T11:59:26.771097Z","end":"2026-04-27T11:59:26.898245Z","steps":["trace[444214184] 'process raft request' (duration: 80.650041ms)","trace[444214184] 'compare' (duration: 46.234918ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:28.867636Z","caller":"traceutil/trace.go:171","msg":"trace[1864191963] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1920; }","duration":"241.692204ms","start":"2026-04-27T11:59:28.625922Z","end":"2026-04-27T11:59:28.867614Z","steps":["trace[1864191963] 'read index received' (duration: 241.47939ms)","trace[1864191963] 'applied index is now lower than readState.Index' (duration: 211.914µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:28.867709Z","caller":"traceutil/trace.go:171","msg":"trace[1529815729] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"371.833533ms","start":"2026-04-27T11:59:28.495836Z","end":"2026-04-27T11:59:28.86767Z","steps":["trace[1529815729] 'process raft request' (duration: 371.638179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:28.867923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T11:59:28.495813Z","time spent":"372.018156ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T11:59:28.86798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.038511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4c2803bc-62a0-49be-ba6d-d44ccbb608fc\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-27T11:59:28.868146Z","caller":"traceutil/trace.go:171","msg":"trace[248028286] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4c2803bc-62a0-49be-ba6d-d44ccbb608fc; range_end:; response_count:1; response_revision:1850; }","duration":"242.254974ms","start":"2026-04-27T11:59:28.625871Z","end":"2026-04-27T11:59:28.868126Z","steps":["trace[248028286] 'agreement among raft nodes before linearized reading' (duration: 241.870607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:29.197599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.388651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:29.197699Z","caller":"traceutil/trace.go:171","msg":"trace[1970414154] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1850; }","duration":"228.549925ms","start":"2026-04-27T11:59:28.969131Z","end":"2026-04-27T11:59:29.197681Z","steps":["trace[1970414154] 'count revisions from in-memory index tree' (duration: 228.299599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:29.197905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.64946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.102\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-27T11:59:29.197955Z","caller":"traceutil/trace.go:171","msg":"trace[1982311460] range","detail":"{range_begin:/registry/masterleases/199.19.213.102; range_end:; response_count:1; response_revision:1850; }","duration":"185.748771ms","start":"2026-04-27T11:59:29.012194Z","end":"2026-04-27T11:59:29.197943Z","steps":["trace[1982311460] 'range keys from in-memory index tree' (duration: 185.526008ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:29.36606Z","caller":"traceutil/trace.go:171","msg":"trace[456496814] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"167.166992ms","start":"2026-04-27T11:59:29.198871Z","end":"2026-04-27T11:59:29.366037Z","steps":["trace[456496814] 'read index received' (duration: 166.922067ms)","trace[456496814] 'applied index is now lower than readState.Index' (duration: 243.195µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T11:59:29.366216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.303774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:29.366268Z","caller":"traceutil/trace.go:171","msg":"trace[1819795807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"167.403887ms","start":"2026-04-27T11:59:29.19885Z","end":"2026-04-27T11:59:29.366254Z","steps":["trace[1819795807] 'agreement among raft nodes before linearized reading' (duration: 167.285385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:29.366321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.963254ms","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-27T11:59:29.366368Z","caller":"traceutil/trace.go:171","msg":"trace[2479030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1850; }","duration":"143.049145ms","start":"2026-04-27T11:59:29.223309Z","end":"2026-04-27T11:59:29.366358Z","steps":["trace[2479030] 'agreement among raft nodes before linearized reading' (duration: 142.876032ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:29.60398Z","caller":"traceutil/trace.go:171","msg":"trace[425521204] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1923; }","duration":"226.661802ms","start":"2026-04-27T11:59:29.377209Z","end":"2026-04-27T11:59:29.603871Z","steps":["trace[425521204] 'read index received' (duration: 226.57938ms)","trace[425521204] 'applied index is now lower than readState.Index' (duration: 81.192µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T11:59:29.603997Z","caller":"traceutil/trace.go:171","msg":"trace[536197248] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"233.312561ms","start":"2026-04-27T11:59:29.370663Z","end":"2026-04-27T11:59:29.603976Z","steps":["trace[536197248] 'process raft request' (duration: 233.023905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:29.604353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.128421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-27T11:59:29.604513Z","caller":"traceutil/trace.go:171","msg":"trace[564502077] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1852; }","duration":"227.320685ms","start":"2026-04-27T11:59:29.377174Z","end":"2026-04-27T11:59:29.604495Z","steps":["trace[564502077] 'agreement among raft nodes before linearized reading' (duration: 226.914537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:29.632865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.452198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T11:59:29.632925Z","caller":"traceutil/trace.go:171","msg":"trace[528333656] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1853; }","duration":"242.554989ms","start":"2026-04-27T11:59:29.390357Z","end":"2026-04-27T11:59:29.632912Z","steps":["trace[528333656] 'agreement among raft nodes before linearized reading' (duration: 242.395106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T11:59:29.63328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.406547ms","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-27T11:59:29.633373Z","caller":"traceutil/trace.go:171","msg":"trace[297264566] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1853; }","duration":"197.554319ms","start":"2026-04-27T11:59:29.435794Z","end":"2026-04-27T11:59:29.633349Z","steps":["trace[297264566] 'agreement among raft nodes before linearized reading' (duration: 197.316785ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T11:59:30.067546Z","caller":"traceutil/trace.go:171","msg":"trace[292375992] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"129.396361ms","start":"2026-04-27T11:59:29.938119Z","end":"2026-04-27T11:59:30.067515Z","steps":["trace[292375992] 'process raft request' (duration: 129.214637ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:04.273601Z","caller":"traceutil/trace.go:171","msg":"trace[926551541] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"444.634953ms","start":"2026-04-27T12:00:03.82894Z","end":"2026-04-27T12:00:04.273575Z","steps":["trace[926551541] 'process raft request' (duration: 444.504881ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:00:04.273657Z","caller":"traceutil/trace.go:171","msg":"trace[1268105780] linearizableReadLoop","detail":"{readStateIndex:2175; appliedIndex:2175; }","duration":"428.38576ms","start":"2026-04-27T12:00:03.845214Z","end":"2026-04-27T12:00:04.2736Z","steps":["trace[1268105780] 'read index received' (duration: 428.37343ms)","trace[1268105780] 'applied index is now lower than readState.Index' (duration: 10.19µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:00:04.273785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:03.828917Z","time spent":"444.790104ms","remote":"127.0.0.1:45212","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-27T12:00:04.273947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.720906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T12:00:04.274023Z","caller":"traceutil/trace.go:171","msg":"trace[1757421834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2097; }","duration":"428.856578ms","start":"2026-04-27T12:00:03.845149Z","end":"2026-04-27T12:00:04.274006Z","steps":["trace[1757421834] 'agreement among raft nodes before linearized reading' (duration: 428.597024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.274061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:03.845124Z","time spent":"428.930589ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T12:00:04.536395Z","caller":"traceutil/trace.go:171","msg":"trace[130260691] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"642.432556ms","start":"2026-04-27T12:00:03.893923Z","end":"2026-04-27T12:00:04.536355Z","steps":["trace[130260691] 'process raft request' (duration: 549.704983ms)","trace[130260691] 'compare' (duration: 92.422899ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:00:04.536431Z","caller":"traceutil/trace.go:171","msg":"trace[704010727] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"258.747093ms","start":"2026-04-27T12:00:04.277658Z","end":"2026-04-27T12:00:04.536405Z","steps":["trace[704010727] 'process raft request' (duration: 258.617381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.53656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:03.89389Z","time spent":"642.596169ms","remote":"127.0.0.1:45212","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-27T12:00:04.53662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.075005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:00:04.536518Z","caller":"traceutil/trace.go:171","msg":"trace[1416566669] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"257.603445ms","start":"2026-04-27T12:00:04.278892Z","end":"2026-04-27T12:00:04.536496Z","steps":["trace[1416566669] 'process raft request' (duration: 257.470773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.537022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.740756ms","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-27T12:00:04.537065Z","caller":"traceutil/trace.go:171","msg":"trace[938889113] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2100; }","duration":"659.840707ms","start":"2026-04-27T12:00:03.877207Z","end":"2026-04-27T12:00:04.537048Z","steps":["trace[938889113] 'agreement among raft nodes before linearized reading' (duration: 659.362469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.537117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:03.877167Z","time spent":"659.941488ms","remote":"127.0.0.1:45212","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-27T12:00:04.537236Z","caller":"traceutil/trace.go:171","msg":"trace[2023357038] linearizableReadLoop","detail":"{readStateIndex:2176; appliedIndex:2175; }","duration":"262.670961ms","start":"2026-04-27T12:00:04.273713Z","end":"2026-04-27T12:00:04.536384Z","steps":["trace[2023357038] 'read index received' (duration: 169.925837ms)","trace[2023357038] 'applied index is now lower than readState.Index' (duration: 92.742584ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:00:04.537271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.703118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:00:04.537514Z","caller":"traceutil/trace.go:171","msg":"trace[20129648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2100; }","duration":"414.110405ms","start":"2026-04-27T12:00:04.123323Z","end":"2026-04-27T12:00:04.537433Z","steps":["trace[20129648] 'agreement among raft nodes before linearized reading' (duration: 413.542936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.537686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:04.123304Z","time spent":"414.293327ms","remote":"127.0.0.1:44930","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-27T12:00:04.537749Z","caller":"traceutil/trace.go:171","msg":"trace[1573499931] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2100; }","duration":"647.39271ms","start":"2026-04-27T12:00:03.889439Z","end":"2026-04-27T12:00:04.536832Z","steps":["trace[1573499931] 'agreement among raft nodes before linearized reading' (duration: 647.011885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.537829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:03.889419Z","time spent":"648.384415ms","remote":"127.0.0.1:59220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-04-27T12:00:04.954503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.019842ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T12:00:04.954735Z","caller":"traceutil/trace.go:171","msg":"trace[1611918275] linearizableReadLoop","detail":"{readStateIndex:2179; appliedIndex:2178; }","duration":"417.424473ms","start":"2026-04-27T12:00:04.537294Z","end":"2026-04-27T12:00:04.954718Z","steps":["trace[1611918275] 'read index received' (duration: 47.35µs)","trace[1611918275] 'applied index is now lower than readState.Index' (duration: 417.376153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:00:04.954869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.877051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:00:04.954903Z","caller":"traceutil/trace.go:171","msg":"trace[325108846] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2100; }","duration":"565.944663ms","start":"2026-04-27T12:00:04.388949Z","end":"2026-04-27T12:00:04.954893Z","steps":["trace[325108846] 'agreement among raft nodes before linearized reading' (duration: 565.860462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.954918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080186372s","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":"warn","ts":"2026-04-27T12:00:04.954936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:04.388936Z","time spent":"565.990914ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":36,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-04-27T12:00:04.95495Z","caller":"traceutil/trace.go:171","msg":"trace[781559372] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2100; }","duration":"1.080256944s","start":"2026-04-27T12:00:03.874681Z","end":"2026-04-27T12:00:04.954938Z","steps":["trace[781559372] 'agreement among raft nodes before linearized reading' (duration: 662.006978ms)","trace[781559372] 'range keys from in-memory index tree' (duration: 418.072804ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:00:04.954979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:03.874657Z","time spent":"1.080313255s","remote":"127.0.0.1:36744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-27T12:00:04.955019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.592943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:00:04.95523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.014354ms","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-27T12:00:04.955274Z","caller":"traceutil/trace.go:171","msg":"trace[1844598793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2100; }","duration":"595.101695ms","start":"2026-04-27T12:00:04.360162Z","end":"2026-04-27T12:00:04.955264Z","steps":["trace[1844598793] 'agreement among raft nodes before linearized reading' (duration: 594.997674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.955303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:04.360118Z","time spent":"595.179156ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T12:00:04.95524Z","caller":"traceutil/trace.go:171","msg":"trace[1625746843] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2100; }","duration":"512.866097ms","start":"2026-04-27T12:00:04.44231Z","end":"2026-04-27T12:00:04.955177Z","steps":["trace[1625746843] 'agreement among raft nodes before linearized reading' (duration: 512.502152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.955378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.330234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-27T12:00:04.955376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:04.442288Z","time spent":"513.06432ms","remote":"127.0.0.1:38896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-27T12:00:04.955441Z","caller":"traceutil/trace.go:171","msg":"trace[1583743344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2100; }","duration":"590.392435ms","start":"2026-04-27T12:00:04.365031Z","end":"2026-04-27T12:00:04.955423Z","steps":["trace[1583743344] 'agreement among raft nodes before linearized reading' (duration: 590.291263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.955235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.293647ms","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":"warn","ts":"2026-04-27T12:00:04.955485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:04.365024Z","time spent":"590.449995ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T12:00:04.955521Z","caller":"traceutil/trace.go:171","msg":"trace[846027482] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"414.164104ms","start":"2026-04-27T12:00:04.541348Z","end":"2026-04-27T12:00:04.955512Z","steps":["trace[846027482] 'process raft request' (duration: 413.249261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.955124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.134291ms","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-27T12:00:04.955582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:04.54133Z","time spent":"414.212555ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:00:04.955305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.816613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T12:00:04.95573Z","caller":"traceutil/trace.go:171","msg":"trace[1115006818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2100; }","duration":"587.25649ms","start":"2026-04-27T12:00:04.368466Z","end":"2026-04-27T12:00:04.955723Z","steps":["trace[1115006818] 'agreement among raft nodes before linearized reading' (duration: 586.791792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.95575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:04.368457Z","time spent":"587.28679ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T12:00:04.955684Z","caller":"traceutil/trace.go:171","msg":"trace[1045677746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2100; }","duration":"590.69772ms","start":"2026-04-27T12:00:04.364942Z","end":"2026-04-27T12:00:04.95564Z","steps":["trace[1045677746] 'agreement among raft nodes before linearized reading' (duration: 589.986039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.955858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:04.364928Z","time spent":"590.895083ms","remote":"127.0.0.1:45212","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-27T12:00:04.955493Z","caller":"traceutil/trace.go:171","msg":"trace[1986150203] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2100; }","duration":"572.572321ms","start":"2026-04-27T12:00:04.382908Z","end":"2026-04-27T12:00:04.95548Z","steps":["trace[1986150203] 'agreement among raft nodes before linearized reading' (duration: 572.261377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.95619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:04.382895Z","time spent":"573.276912ms","remote":"127.0.0.1:45212","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-27T12:00:04.971332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.513691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:00:04.971397Z","caller":"traceutil/trace.go:171","msg":"trace[1792783111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2101; }","duration":"431.612683ms","start":"2026-04-27T12:00:04.539771Z","end":"2026-04-27T12:00:04.971384Z","steps":["trace[1792783111] 'agreement among raft nodes before linearized reading' (duration: 415.725447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:00:04.971425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:00:04.539755Z","time spent":"431.665254ms","remote":"127.0.0.1:44914","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-27T12:01:09.079595Z","caller":"traceutil/trace.go:171","msg":"trace[766974587] linearizableReadLoop","detail":"{readStateIndex:2635; appliedIndex:2634; }","duration":"240.681214ms","start":"2026-04-27T12:01:08.838891Z","end":"2026-04-27T12:01:09.079572Z","steps":["trace[766974587] 'read index received' (duration: 240.443342ms)","trace[766974587] 'applied index is now lower than readState.Index' (duration: 236.502µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:09.079681Z","caller":"traceutil/trace.go:171","msg":"trace[825012284] transaction","detail":"{read_only:false; response_revision:2542; number_of_response:1; }","duration":"317.474661ms","start":"2026-04-27T12:01:08.762173Z","end":"2026-04-27T12:01:09.079648Z","steps":["trace[825012284] 'process raft request' (duration: 317.266579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:09.079835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:08.762149Z","time spent":"317.607952ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:01:09.079974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.629549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:01:09.080047Z","caller":"traceutil/trace.go:171","msg":"trace[219911131] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2542; }","duration":"202.7615ms","start":"2026-04-27T12:01:08.877267Z","end":"2026-04-27T12:01:09.080028Z","steps":["trace[219911131] 'agreement among raft nodes before linearized reading' (duration: 202.549757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:09.080378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.472123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T12:01:09.080436Z","caller":"traceutil/trace.go:171","msg":"trace[1147910850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2542; }","duration":"241.560884ms","start":"2026-04-27T12:01:08.838863Z","end":"2026-04-27T12:01:09.080424Z","steps":["trace[1147910850] 'agreement among raft nodes before linearized reading' (duration: 240.832786ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:37.627806Z","caller":"traceutil/trace.go:171","msg":"trace[819055490] linearizableReadLoop","detail":"{readStateIndex:2804; appliedIndex:2803; }","duration":"103.202111ms","start":"2026-04-27T12:01:37.52458Z","end":"2026-04-27T12:01:37.627782Z","steps":["trace[819055490] 'read index received' (duration: 102.985199ms)","trace[819055490] 'applied index is now lower than readState.Index' (duration: 215.982µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:37.627866Z","caller":"traceutil/trace.go:171","msg":"trace[1867199762] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"109.374263ms","start":"2026-04-27T12:01:37.518451Z","end":"2026-04-27T12:01:37.627825Z","steps":["trace[1867199762] 'process raft request' (duration: 109.165581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:37.628033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.444643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-27T12:01:37.628348Z","caller":"traceutil/trace.go:171","msg":"trace[183557885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2704; }","duration":"103.803306ms","start":"2026-04-27T12:01:37.52453Z","end":"2026-04-27T12:01:37.628333Z","steps":["trace[183557885] 'agreement among raft nodes before linearized reading' (duration: 103.386583ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:43.35263Z","caller":"traceutil/trace.go:171","msg":"trace[728973333] linearizableReadLoop","detail":"{readStateIndex:2830; appliedIndex:2829; }","duration":"103.524601ms","start":"2026-04-27T12:01:43.249077Z","end":"2026-04-27T12:01:43.352602Z","steps":["trace[728973333] 'read index received' (duration: 103.287279ms)","trace[728973333] 'applied index is now lower than readState.Index' (duration: 235.922µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:01:43.352931Z","caller":"traceutil/trace.go:171","msg":"trace[1943297329] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"156.930983ms","start":"2026-04-27T12:01:43.195978Z","end":"2026-04-27T12:01:43.352909Z","steps":["trace[1943297329] 'process raft request' (duration: 156.455548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.353205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.102976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T12:01:43.353639Z","caller":"traceutil/trace.go:171","msg":"trace[505790178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2729; }","duration":"104.465761ms","start":"2026-04-27T12:01:43.249012Z","end":"2026-04-27T12:01:43.353478Z","steps":["trace[505790178] 'agreement among raft nodes before linearized reading' (duration: 103.974436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.782866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.895533ms","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-27T12:01:43.78334Z","caller":"traceutil/trace.go:171","msg":"trace[1829484020] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2730; }","duration":"132.412679ms","start":"2026-04-27T12:01:43.650912Z","end":"2026-04-27T12:01:43.783324Z","steps":["trace[1829484020] 'range keys from in-memory index tree' (duration: 131.750702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.783359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.480861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-27T12:01:43.782866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.40322ms","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-27T12:01:43.783461Z","caller":"traceutil/trace.go:171","msg":"trace[1605621696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2730; }","duration":"109.632223ms","start":"2026-04-27T12:01:43.673805Z","end":"2026-04-27T12:01:43.783437Z","steps":["trace[1605621696] 'range keys from in-memory index tree' (duration: 109.275538ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:43.783535Z","caller":"traceutil/trace.go:171","msg":"trace[1340333792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2730; }","duration":"314.088648ms","start":"2026-04-27T12:01:43.469399Z","end":"2026-04-27T12:01:43.783488Z","steps":["trace[1340333792] 'range keys from in-memory index tree' (duration: 313.209309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.783601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:43.469366Z","time spent":"314.208199ms","remote":"127.0.0.1:45212","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-27T12:01:43.782945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.952315ms","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-27T12:01:43.783692Z","caller":"traceutil/trace.go:171","msg":"trace[1409961205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2730; }","duration":"310.724814ms","start":"2026-04-27T12:01:43.472951Z","end":"2026-04-27T12:01:43.783676Z","steps":["trace[1409961205] 'range keys from in-memory index tree' (duration: 309.851475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.783022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.053917ms","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":"warn","ts":"2026-04-27T12:01:43.783746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:43.472934Z","time spent":"310.795215ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T12:01:43.783176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.779011ms","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-27T12:01:43.783818Z","caller":"traceutil/trace.go:171","msg":"trace[551249323] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2730; }","duration":"301.872546ms","start":"2026-04-27T12:01:43.481912Z","end":"2026-04-27T12:01:43.783784Z","steps":["trace[551249323] 'range keys from in-memory index tree' (duration: 300.897286ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:43.783882Z","caller":"traceutil/trace.go:171","msg":"trace[413517829] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2730; }","duration":"126.494689ms","start":"2026-04-27T12:01:43.657368Z","end":"2026-04-27T12:01:43.783863Z","steps":["trace[413517829] 'range keys from in-memory index tree' (duration: 125.689541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:01:43.78393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:01:43.481876Z","time spent":"302.007867ms","remote":"127.0.0.1:45212","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-27T12:01:43.912235Z","caller":"traceutil/trace.go:171","msg":"trace[310011758] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"123.797623ms","start":"2026-04-27T12:01:43.788417Z","end":"2026-04-27T12:01:43.912214Z","steps":["trace[310011758] 'process raft request' (duration: 123.681292ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:43.928569Z","caller":"traceutil/trace.go:171","msg":"trace[1555680671] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"138.786482ms","start":"2026-04-27T12:01:43.789767Z","end":"2026-04-27T12:01:43.928553Z","steps":["trace[1555680671] 'process raft request' (duration: 138.743582ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:43.928739Z","caller":"traceutil/trace.go:171","msg":"trace[144079545] transaction","detail":"{read_only:false; response_revision:2733; number_of_response:1; }","duration":"139.359487ms","start":"2026-04-27T12:01:43.789365Z","end":"2026-04-27T12:01:43.928725Z","steps":["trace[144079545] 'process raft request' (duration: 138.974973ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:43.928994Z","caller":"traceutil/trace.go:171","msg":"trace[1059945238] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"139.481829ms","start":"2026-04-27T12:01:43.789489Z","end":"2026-04-27T12:01:43.928971Z","steps":["trace[1059945238] 'process raft request' (duration: 138.982994ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:43.929073Z","caller":"traceutil/trace.go:171","msg":"trace[591870680] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"139.724832ms","start":"2026-04-27T12:01:43.78933Z","end":"2026-04-27T12:01:43.929055Z","steps":["trace[591870680] 'process raft request' (duration: 138.726402ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:01:43.92891Z","caller":"traceutil/trace.go:171","msg":"trace[579746598] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"139.384467ms","start":"2026-04-27T12:01:43.789384Z","end":"2026-04-27T12:01:43.928769Z","steps":["trace[579746598] 'process raft request' (duration: 139.051824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:41.363459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.733585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-ccf849c5f-xct5x.18aa353d4601dc30\" ","response":"range_response_count:1 size:895"} {"level":"warn","ts":"2026-04-27T12:05:41.363532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.833884ms","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-27T12:05:41.363621Z","caller":"traceutil/trace.go:171","msg":"trace[264098666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4740; }","duration":"162.994345ms","start":"2026-04-27T12:05:41.200602Z","end":"2026-04-27T12:05:41.363596Z","steps":["trace[264098666] 'range keys from in-memory index tree' (duration: 162.631561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:41.363661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.265382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d3e37c40-b9c9-4328-9f8e-e2ebb695f873\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-27T12:05:41.363693Z","caller":"traceutil/trace.go:171","msg":"trace[713118084] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d3e37c40-b9c9-4328-9f8e-e2ebb695f873; range_end:; response_count:1; response_revision:4740; }","duration":"120.326683ms","start":"2026-04-27T12:05:41.243356Z","end":"2026-04-27T12:05:41.363683Z","steps":["trace[713118084] 'range keys from in-memory index tree' (duration: 120.153361ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:41.363556Z","caller":"traceutil/trace.go:171","msg":"trace[439459062] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-ccf849c5f-xct5x.18aa353d4601dc30; range_end:; response_count:1; response_revision:4740; }","duration":"119.852717ms","start":"2026-04-27T12:05:41.243681Z","end":"2026-04-27T12:05:41.363534Z","steps":["trace[439459062] 'range keys from in-memory index tree' (duration: 119.562263ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:41.524129Z","caller":"traceutil/trace.go:171","msg":"trace[555081463] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"155.740386ms","start":"2026-04-27T12:05:41.368311Z","end":"2026-04-27T12:05:41.524052Z","steps":["trace[555081463] 'process raft request' (duration: 136.043402ms)","trace[555081463] 'compare' (duration: 19.264157ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:05:41.524283Z","caller":"traceutil/trace.go:171","msg":"trace[484403265] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"152.918946ms","start":"2026-04-27T12:05:41.371198Z","end":"2026-04-27T12:05:41.524117Z","steps":["trace[484403265] 'process raft request' (duration: 152.805095ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:41.524464Z","caller":"traceutil/trace.go:171","msg":"trace[1967827561] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"154.808383ms","start":"2026-04-27T12:05:41.369323Z","end":"2026-04-27T12:05:41.524132Z","steps":["trace[1967827561] 'process raft request' (duration: 154.412666ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:41.531295Z","caller":"traceutil/trace.go:171","msg":"trace[838560345] linearizableReadLoop","detail":"{readStateIndex:4925; appliedIndex:4921; }","duration":"124.853926ms","start":"2026-04-27T12:05:41.406418Z","end":"2026-04-27T12:05:41.531272Z","steps":["trace[838560345] 'read index received' (duration: 98.048923ms)","trace[838560345] 'applied index is now lower than readState.Index' (duration: 26.803803ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:05:41.53142Z","caller":"traceutil/trace.go:171","msg":"trace[1994261623] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"130.902741ms","start":"2026-04-27T12:05:41.400504Z","end":"2026-04-27T12:05:41.531407Z","steps":["trace[1994261623] 'process raft request' (duration: 130.634037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:41.531808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.362272ms","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-27T12:05:41.531889Z","caller":"traceutil/trace.go:171","msg":"trace[1834301594] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4745; }","duration":"125.491734ms","start":"2026-04-27T12:05:41.406373Z","end":"2026-04-27T12:05:41.531865Z","steps":["trace[1834301594] 'agreement among raft nodes before linearized reading' (duration: 125.125259ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:41.69818Z","caller":"traceutil/trace.go:171","msg":"trace[1709823974] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"160.149846ms","start":"2026-04-27T12:05:41.537758Z","end":"2026-04-27T12:05:41.698156Z","steps":["trace[1709823974] 'process raft request' (duration: 159.934033ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:41.698407Z","caller":"traceutil/trace.go:171","msg":"trace[16324264] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"160.165216ms","start":"2026-04-27T12:05:41.538234Z","end":"2026-04-27T12:05:41.698399Z","steps":["trace[16324264] 'process raft request' (duration: 159.877522ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:41.698571Z","caller":"traceutil/trace.go:171","msg":"trace[231706732] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"159.724931ms","start":"2026-04-27T12:05:41.538835Z","end":"2026-04-27T12:05:41.69856Z","steps":["trace[231706732] 'process raft request' (duration: 159.524109ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:53.172759Z","caller":"traceutil/trace.go:171","msg":"trace[347327554] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"131.453275ms","start":"2026-04-27T12:05:53.041277Z","end":"2026-04-27T12:05:53.172731Z","steps":["trace[347327554] 'process raft request' (duration: 131.01522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:53.526628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.434589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-27T12:05:53.526704Z","caller":"traceutil/trace.go:171","msg":"trace[364509598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4908; }","duration":"313.545821ms","start":"2026-04-27T12:05:53.21314Z","end":"2026-04-27T12:05:53.526685Z","steps":["trace[364509598] 'range keys from in-memory index tree' (duration: 313.231447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:53.526745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:53.213126Z","time spent":"313.610562ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T12:05:53.526744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.164988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:05:53.526839Z","caller":"traceutil/trace.go:171","msg":"trace[1248488759] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:4908; }","duration":"269.27241ms","start":"2026-04-27T12:05:53.257511Z","end":"2026-04-27T12:05:53.526784Z","steps":["trace[1248488759] 'count revisions from in-memory index tree' (duration: 269.037886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:20.056135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.434031ms","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-27T12:06:20.05624Z","caller":"traceutil/trace.go:171","msg":"trace[1395512690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5190; }","duration":"112.539773ms","start":"2026-04-27T12:06:19.943677Z","end":"2026-04-27T12:06:20.056217Z","steps":["trace[1395512690] 'range keys from in-memory index tree' (duration: 112.248569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:20.056134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.931879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-27T12:06:20.056601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.633437ms","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-27T12:06:20.056682Z","caller":"traceutil/trace.go:171","msg":"trace[908278533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5190; }","duration":"117.490076ms","start":"2026-04-27T12:06:19.939137Z","end":"2026-04-27T12:06:20.056627Z","steps":["trace[908278533] 'range keys from in-memory index tree' (duration: 116.741287ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:20.056707Z","caller":"traceutil/trace.go:171","msg":"trace[653519614] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5190; }","duration":"163.779819ms","start":"2026-04-27T12:06:19.892893Z","end":"2026-04-27T12:06:20.056673Z","steps":["trace[653519614] 'range keys from in-memory index tree' (duration: 163.412734ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:21.960108Z","caller":"traceutil/trace.go:171","msg":"trace[1017677413] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"113.861366ms","start":"2026-04-27T12:06:21.846211Z","end":"2026-04-27T12:06:21.960072Z","steps":["trace[1017677413] 'process raft request' (duration: 38.000002ms)","trace[1017677413] 'compare' (duration: 75.725273ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:06:31.079967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.272755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-04-27T12:06:31.080047Z","caller":"traceutil/trace.go:171","msg":"trace[1769233392] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5246; }","duration":"919.387657ms","start":"2026-04-27T12:06:30.16064Z","end":"2026-04-27T12:06:31.080028Z","steps":["trace[1769233392] 'range keys from in-memory index tree' (duration: 919.091112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.080039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.546974ms","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":"warn","ts":"2026-04-27T12:06:31.080095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:30.160625Z","time spent":"919.457228ms","remote":"127.0.0.1:45042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3488,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-04-27T12:06:31.080117Z","caller":"traceutil/trace.go:171","msg":"trace[1057403797] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5246; }","duration":"892.679625ms","start":"2026-04-27T12:06:30.187416Z","end":"2026-04-27T12:06:31.080096Z","steps":["trace[1057403797] 'range keys from in-memory index tree' (duration: 892.322901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.080114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.571307ms","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-27T12:06:31.080159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:30.187394Z","time spent":"892.754116ms","remote":"127.0.0.1:45212","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-27T12:06:31.080196Z","caller":"traceutil/trace.go:171","msg":"trace[247234754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5246; }","duration":"875.690038ms","start":"2026-04-27T12:06:30.204488Z","end":"2026-04-27T12:06:31.080178Z","steps":["trace[247234754] 'range keys from in-memory index tree' (duration: 875.394895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.080211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.386133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:06:31.080241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:30.204471Z","time spent":"875.761259ms","remote":"127.0.0.1:45212","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-27T12:06:31.080327Z","caller":"traceutil/trace.go:171","msg":"trace[830371214] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5246; }","duration":"896.524225ms","start":"2026-04-27T12:06:30.183726Z","end":"2026-04-27T12:06:31.08025Z","steps":["trace[830371214] 'range keys from in-memory index tree' (duration: 896.27316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.080414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:30.183702Z","time spent":"896.700967ms","remote":"127.0.0.1:45184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-04-27T12:06:31.080577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.95291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:31.08065Z","caller":"traceutil/trace.go:171","msg":"trace[703200322] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5246; }","duration":"897.065032ms","start":"2026-04-27T12:06:30.183565Z","end":"2026-04-27T12:06:31.08063Z","steps":["trace[703200322] 'range keys from in-memory index tree' (duration: 896.888439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.080704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:30.183552Z","time spent":"897.139432ms","remote":"127.0.0.1:45184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-04-27T12:06:31.080913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.612705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T12:06:31.080974Z","caller":"traceutil/trace.go:171","msg":"trace[1591977740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5246; }","duration":"835.710365ms","start":"2026-04-27T12:06:30.245248Z","end":"2026-04-27T12:06:31.080959Z","steps":["trace[1591977740] 'range keys from in-memory index tree' (duration: 834.560341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.081019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:30.245237Z","time spent":"835.771536ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T12:06:31.080118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.017142ms","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-27T12:06:31.081975Z","caller":"traceutil/trace.go:171","msg":"trace[426736978] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5246; }","duration":"895.888656ms","start":"2026-04-27T12:06:30.18607Z","end":"2026-04-27T12:06:31.081959Z","steps":["trace[426736978] 'range keys from in-memory index tree' (duration: 893.920882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.082121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:30.18606Z","time spent":"896.048228ms","remote":"127.0.0.1:45184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6182,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"info","ts":"2026-04-27T12:06:31.263448Z","caller":"traceutil/trace.go:171","msg":"trace[549261937] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"762.116451ms","start":"2026-04-27T12:06:30.501303Z","end":"2026-04-27T12:06:31.263419Z","steps":["trace[549261937] 'process raft request' (duration: 761.865877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.263785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:30.501277Z","time spent":"762.256863ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:06:31.433013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.610163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:06:31.433205Z","caller":"traceutil/trace.go:171","msg":"trace[2025105220] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"347.845017ms","start":"2026-04-27T12:06:31.085345Z","end":"2026-04-27T12:06:31.43319Z","steps":["trace[2025105220] 'process raft request' (duration: 347.756996ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:31.433207Z","caller":"traceutil/trace.go:171","msg":"trace[1738875129] transaction","detail":"{read_only:false; response_revision:5248; number_of_response:1; }","duration":"711.725382ms","start":"2026-04-27T12:06:30.721458Z","end":"2026-04-27T12:06:31.433183Z","steps":["trace[1738875129] 'process raft request' (duration: 593.872686ms)","trace[1738875129] 'compare' (duration: 117.490391ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:06:31.433414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:30.721435Z","time spent":"711.919064ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:06:31.433277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:31.085326Z","time spent":"347.917387ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:06:31.433578Z","caller":"traceutil/trace.go:171","msg":"trace[1368668166] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"348.097899ms","start":"2026-04-27T12:06:31.085368Z","end":"2026-04-27T12:06:31.433466Z","steps":["trace[1368668166] 'process raft request' (duration: 347.790825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.434402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:31.085357Z","time spent":"348.982312ms","remote":"127.0.0.1:45212","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-27T12:06:31.433901Z","caller":"traceutil/trace.go:171","msg":"trace[1222390463] linearizableReadLoop","detail":"{readStateIndex:5444; appliedIndex:5442; }","duration":"661.109574ms","start":"2026-04-27T12:06:30.772774Z","end":"2026-04-27T12:06:31.433884Z","steps":["trace[1222390463] 'read index received' (duration: 490.412402ms)","trace[1222390463] 'applied index is now lower than readState.Index' (duration: 170.695812ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:06:31.433981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.192415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:31.434647Z","caller":"traceutil/trace.go:171","msg":"trace[1869268144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5251; }","duration":"661.899554ms","start":"2026-04-27T12:06:30.772726Z","end":"2026-04-27T12:06:31.434626Z","steps":["trace[1869268144] 'agreement among raft nodes before linearized reading' (duration: 661.202935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.434715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:30.77271Z","time spent":"661.988046ms","remote":"127.0.0.1:44930","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-27T12:06:31.43402Z","caller":"traceutil/trace.go:171","msg":"trace[908299823] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"348.581326ms","start":"2026-04-27T12:06:31.08542Z","end":"2026-04-27T12:06:31.434001Z","steps":["trace[908299823] 'process raft request' (duration: 347.863247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.435035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:31.08541Z","time spent":"349.513358ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:06:31.435209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.519105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-04-27T12:06:31.435275Z","caller":"traceutil/trace.go:171","msg":"trace[516940400] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5251; }","duration":"348.602816ms","start":"2026-04-27T12:06:31.086656Z","end":"2026-04-27T12:06:31.435258Z","steps":["trace[516940400] 'agreement among raft nodes before linearized reading' (duration: 348.443794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.435327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:31.086648Z","time spent":"348.665256ms","remote":"127.0.0.1:45042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3488,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-04-27T12:06:31.43548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.163199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T12:06:31.435545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.318835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:31.435586Z","caller":"traceutil/trace.go:171","msg":"trace[323563826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5251; }","duration":"313.391946ms","start":"2026-04-27T12:06:31.122181Z","end":"2026-04-27T12:06:31.435572Z","steps":["trace[323563826] 'agreement among raft nodes before linearized reading' (duration: 313.329465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.435626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:31.122164Z","time spent":"313.454116ms","remote":"127.0.0.1:44914","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-27T12:06:31.435535Z","caller":"traceutil/trace.go:171","msg":"trace[2077240572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5251; }","duration":"391.290822ms","start":"2026-04-27T12:06:31.044229Z","end":"2026-04-27T12:06:31.43552Z","steps":["trace[2077240572] 'agreement among raft nodes before linearized reading' (duration: 391.026058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.435731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:31.04421Z","time spent":"391.502534ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T12:06:31.434992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.235501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-27T12:06:31.435807Z","caller":"traceutil/trace.go:171","msg":"trace[2060538391] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5251; }","duration":"525.095683ms","start":"2026-04-27T12:06:30.910699Z","end":"2026-04-27T12:06:31.435794Z","steps":["trace[2060538391] 'agreement among raft nodes before linearized reading' (duration: 524.171471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.435852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:30.910675Z","time spent":"525.166184ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-27T12:06:31.703061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.13598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:06:31.703155Z","caller":"traceutil/trace.go:171","msg":"trace[1351454708] linearizableReadLoop","detail":"{readStateIndex:5448; appliedIndex:5447; }","duration":"258.409503ms","start":"2026-04-27T12:06:31.444729Z","end":"2026-04-27T12:06:31.703138Z","steps":["trace[1351454708] 'read index received' (duration: 97.245354ms)","trace[1351454708] 'applied index is now lower than readState.Index' (duration: 161.163039ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:31.703168Z","caller":"traceutil/trace.go:171","msg":"trace[736238325] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"260.227936ms","start":"2026-04-27T12:06:31.442928Z","end":"2026-04-27T12:06:31.703156Z","steps":["trace[736238325] 'process raft request' (duration: 98.905883ms)","trace[736238325] 'compare' (duration: 160.853976ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:06:31.703301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.565035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:31.703335Z","caller":"traceutil/trace.go:171","msg":"trace[1420587777] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:5252; }","duration":"258.610576ms","start":"2026-04-27T12:06:31.444713Z","end":"2026-04-27T12:06:31.703323Z","steps":["trace[1420587777] 'agreement among raft nodes before linearized reading' (duration: 258.508604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.703395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.598316ms","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-27T12:06:31.703417Z","caller":"traceutil/trace.go:171","msg":"trace[626409065] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5252; }","duration":"208.652257ms","start":"2026-04-27T12:06:31.49476Z","end":"2026-04-27T12:06:31.703412Z","steps":["trace[626409065] 'agreement among raft nodes before linearized reading' (duration: 208.612766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.703529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.625573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-27T12:06:31.703552Z","caller":"traceutil/trace.go:171","msg":"trace[1168141266] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5252; }","duration":"185.669953ms","start":"2026-04-27T12:06:31.517875Z","end":"2026-04-27T12:06:31.703545Z","steps":["trace[1168141266] 'agreement among raft nodes before linearized reading' (duration: 185.588852ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:31.972353Z","caller":"traceutil/trace.go:171","msg":"trace[920175599] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"263.800691ms","start":"2026-04-27T12:06:31.708525Z","end":"2026-04-27T12:06:31.972326Z","steps":["trace[920175599] 'process raft request' (duration: 191.77143ms)","trace[920175599] 'compare' (duration: 71.784888ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:31.972457Z","caller":"traceutil/trace.go:171","msg":"trace[1469288765] linearizableReadLoop","detail":"{readStateIndex:5449; appliedIndex:5448; }","duration":"262.8439ms","start":"2026-04-27T12:06:31.709584Z","end":"2026-04-27T12:06:31.972428Z","steps":["trace[1469288765] 'read index received' (duration: 190.725028ms)","trace[1469288765] 'applied index is now lower than readState.Index' (duration: 72.116282ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:31.97243Z","caller":"traceutil/trace.go:171","msg":"trace[614374049] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"157.895489ms","start":"2026-04-27T12:06:31.814507Z","end":"2026-04-27T12:06:31.972403Z","steps":["trace[614374049] 'process raft request' (duration: 157.793438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:31.972728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.128503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-04-27T12:06:31.972962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.190381ms","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-27T12:06:31.973022Z","caller":"traceutil/trace.go:171","msg":"trace[1898335107] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:5254; }","duration":"262.281472ms","start":"2026-04-27T12:06:31.710723Z","end":"2026-04-27T12:06:31.973005Z","steps":["trace[1898335107] 'agreement among raft nodes before linearized reading' (duration: 262.038509ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:31.973263Z","caller":"traceutil/trace.go:171","msg":"trace[322899743] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5254; }","duration":"263.218284ms","start":"2026-04-27T12:06:31.709534Z","end":"2026-04-27T12:06:31.972752Z","steps":["trace[322899743] 'agreement among raft nodes before linearized reading' (duration: 263.038062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:32.318758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.328135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:06:32.319084Z","caller":"traceutil/trace.go:171","msg":"trace[1946369758] linearizableReadLoop","detail":"{readStateIndex:5452; appliedIndex:5450; }","duration":"339.267752ms","start":"2026-04-27T12:06:31.979759Z","end":"2026-04-27T12:06:32.319027Z","steps":["trace[1946369758] 'read index received' (duration: 99.627232ms)","trace[1946369758] 'applied index is now lower than readState.Index' (duration: 239.63988ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:32.319137Z","caller":"traceutil/trace.go:171","msg":"trace[2078340615] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"341.54829ms","start":"2026-04-27T12:06:31.977565Z","end":"2026-04-27T12:06:32.319113Z","steps":["trace[2078340615] 'process raft request' (duration: 341.338756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:32.319295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.520435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"warn","ts":"2026-04-27T12:06:32.319324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:31.977544Z","time spent":"341.700702ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:06:32.319351Z","caller":"traceutil/trace.go:171","msg":"trace[44655467] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5256; }","duration":"339.595756ms","start":"2026-04-27T12:06:31.979742Z","end":"2026-04-27T12:06:32.319338Z","steps":["trace[44655467] 'agreement among raft nodes before linearized reading' (duration: 339.398993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:32.319392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:31.979717Z","time spent":"339.666326ms","remote":"127.0.0.1:45142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":1016,"request content":"key:\"/registry/services/specs/monitoring/prometheus-operated\" "} {"level":"warn","ts":"2026-04-27T12:06:32.319445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.16232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:32.319497Z","caller":"traceutil/trace.go:171","msg":"trace[1193726835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5256; }","duration":"198.256191ms","start":"2026-04-27T12:06:32.121231Z","end":"2026-04-27T12:06:32.319487Z","steps":["trace[1193726835] 'agreement among raft nodes before linearized reading' (duration: 198.150899ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:32.31914Z","caller":"traceutil/trace.go:171","msg":"trace[690383378] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"342.154318ms","start":"2026-04-27T12:06:31.976958Z","end":"2026-04-27T12:06:32.319112Z","steps":["trace[690383378] 'process raft request' (duration: 102.402687ms)","trace[690383378] 'compare' (duration: 239.239744ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:06:32.319575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.900089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:06:32.319683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.219504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-27T12:06:32.319683Z","caller":"traceutil/trace.go:171","msg":"trace[1606985585] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:5256; }","duration":"141.048201ms","start":"2026-04-27T12:06:32.178618Z","end":"2026-04-27T12:06:32.319666Z","steps":["trace[1606985585] 'agreement among raft nodes before linearized reading' (duration: 140.702397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:32.319706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:31.976943Z","time spent":"342.652914ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:06:32.319739Z","caller":"traceutil/trace.go:171","msg":"trace[147085982] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5256; }","duration":"333.308874ms","start":"2026-04-27T12:06:31.986418Z","end":"2026-04-27T12:06:32.319727Z","steps":["trace[147085982] 'agreement among raft nodes before linearized reading' (duration: 332.90307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:32.319806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:31.986408Z","time spent":"333.390025ms","remote":"127.0.0.1:36744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-27T12:06:32.319917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.545294ms","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-27T12:06:32.31997Z","caller":"traceutil/trace.go:171","msg":"trace[1478396341] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5256; }","duration":"216.632825ms","start":"2026-04-27T12:06:32.103321Z","end":"2026-04-27T12:06:32.319954Z","steps":["trace[1478396341] 'agreement among raft nodes before linearized reading' (duration: 216.356262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:32.666941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.64936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:06:32.6673Z","caller":"traceutil/trace.go:171","msg":"trace[538826068] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"341.872593ms","start":"2026-04-27T12:06:32.325396Z","end":"2026-04-27T12:06:32.667268Z","steps":["trace[538826068] 'process raft request' (duration: 104.797187ms)","trace[538826068] 'compare' (duration: 236.534079ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:32.667428Z","caller":"traceutil/trace.go:171","msg":"trace[1785847129] linearizableReadLoop","detail":"{readStateIndex:5453; appliedIndex:5452; }","duration":"341.459938ms","start":"2026-04-27T12:06:32.325565Z","end":"2026-04-27T12:06:32.667025Z","steps":["trace[1785847129] 'read index received' (duration: 104.578295ms)","trace[1785847129] 'applied index is now lower than readState.Index' (duration: 236.880483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:06:32.667649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.072856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61961"} {"level":"info","ts":"2026-04-27T12:06:32.667693Z","caller":"traceutil/trace.go:171","msg":"trace[1641930336] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:5257; }","duration":"342.271039ms","start":"2026-04-27T12:06:32.32541Z","end":"2026-04-27T12:06:32.667681Z","steps":["trace[1641930336] 'agreement among raft nodes before linearized reading' (duration: 342.046755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:32.66773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:32.325393Z","time spent":"342.328459ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":6,"response size":61984,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"warn","ts":"2026-04-27T12:06:32.668066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.394636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-27T12:06:32.667495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:32.325371Z","time spent":"341.987695ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:06:32.668114Z","caller":"traceutil/trace.go:171","msg":"trace[1573224269] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5257; }","duration":"261.475678ms","start":"2026-04-27T12:06:32.406624Z","end":"2026-04-27T12:06:32.6681Z","steps":["trace[1573224269] 'agreement among raft nodes before linearized reading' (duration: 261.361646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:33.008228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.290071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:06:33.008417Z","caller":"traceutil/trace.go:171","msg":"trace[703962781] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"337.445157ms","start":"2026-04-27T12:06:32.670931Z","end":"2026-04-27T12:06:33.008376Z","steps":["trace[703962781] 'process raft request' (duration: 98.892302ms)","trace[703962781] 'compare' (duration: 238.044449ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:06:33.008489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:32.670909Z","time spent":"337.548279ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:06:33.05889Z","caller":"traceutil/trace.go:171","msg":"trace[177862055] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"379.758465ms","start":"2026-04-27T12:06:32.679103Z","end":"2026-04-27T12:06:33.058861Z","steps":["trace[177862055] 'process raft request' (duration: 379.554142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:33.059152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:32.679066Z","time spent":"379.997148ms","remote":"127.0.0.1:34828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:06:33.664336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.179995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:06:33.664524Z","caller":"traceutil/trace.go:171","msg":"trace[184410432] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"818.038979ms","start":"2026-04-27T12:06:32.846453Z","end":"2026-04-27T12:06:33.664492Z","steps":["trace[184410432] 'process raft request' (duration: 481.5984ms)","trace[184410432] 'compare' (duration: 336.048323ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:33.664596Z","caller":"traceutil/trace.go:171","msg":"trace[1713267219] linearizableReadLoop","detail":"{readStateIndex:5456; appliedIndex:5455; }","duration":"578.805268ms","start":"2026-04-27T12:06:33.085767Z","end":"2026-04-27T12:06:33.664573Z","steps":["trace[1713267219] 'read index received' (duration: 242.296679ms)","trace[1713267219] 'applied index is now lower than readState.Index' (duration: 336.506329ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:06:33.664678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:32.846426Z","time spent":"818.16785ms","remote":"127.0.0.1:45212","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-27T12:06:33.664921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.915699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:06:33.664963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.702428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:06:33.665039Z","caller":"traceutil/trace.go:171","msg":"trace[77245638] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"395.871905ms","start":"2026-04-27T12:06:33.268914Z","end":"2026-04-27T12:06:33.664786Z","steps":["trace[77245638] 'process raft request' (duration: 395.589952ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:33.665041Z","caller":"traceutil/trace.go:171","msg":"trace[250946306] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:5261; }","duration":"364.81982ms","start":"2026-04-27T12:06:33.300203Z","end":"2026-04-27T12:06:33.665023Z","steps":["trace[250946306] 'agreement among raft nodes before linearized reading' (duration: 364.694309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:33.665089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:33.300162Z","time spent":"364.917451ms","remote":"127.0.0.1:34828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true "} {"level":"warn","ts":"2026-04-27T12:06:33.665153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.786269ms","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-27T12:06:33.665173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:33.268889Z","time spent":"396.189579ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:06:33.665213Z","caller":"traceutil/trace.go:171","msg":"trace[1482057336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5261; }","duration":"222.86704ms","start":"2026-04-27T12:06:33.442333Z","end":"2026-04-27T12:06:33.6652Z","steps":["trace[1482057336] 'agreement among raft nodes before linearized reading' (duration: 222.691408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:33.66518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.921579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T12:06:33.665238Z","caller":"traceutil/trace.go:171","msg":"trace[909817895] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5261; }","duration":"579.023161ms","start":"2026-04-27T12:06:33.085949Z","end":"2026-04-27T12:06:33.664972Z","steps":["trace[909817895] 'agreement among raft nodes before linearized reading' (duration: 578.927719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:33.665295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.220173ms","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-27T12:06:33.665365Z","caller":"traceutil/trace.go:171","msg":"trace[1039846592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5261; }","duration":"222.080801ms","start":"2026-04-27T12:06:33.443228Z","end":"2026-04-27T12:06:33.665309Z","steps":["trace[1039846592] 'agreement among raft nodes before linearized reading' (duration: 221.691986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:33.665415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.435357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:33.665456Z","caller":"traceutil/trace.go:171","msg":"trace[32567419] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:5261; }","duration":"474.506417ms","start":"2026-04-27T12:06:33.19094Z","end":"2026-04-27T12:06:33.665446Z","steps":["trace[32567419] 'agreement among raft nodes before linearized reading' (duration: 474.443987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:33.665492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:33.190922Z","time spent":"474.562539ms","remote":"127.0.0.1:38982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-04-27T12:06:33.66552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.636415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:33.665576Z","caller":"traceutil/trace.go:171","msg":"trace[2121303874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5261; }","duration":"542.713746ms","start":"2026-04-27T12:06:33.122848Z","end":"2026-04-27T12:06:33.665562Z","steps":["trace[2121303874] 'agreement among raft nodes before linearized reading' (duration: 542.635975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:33.665588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.541766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:33.665824Z","caller":"traceutil/trace.go:171","msg":"trace[349726429] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5261; }","duration":"579.80363ms","start":"2026-04-27T12:06:33.086006Z","end":"2026-04-27T12:06:33.66581Z","steps":["trace[349726429] 'agreement among raft nodes before linearized reading' (duration: 579.548097ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:33.66538Z","caller":"traceutil/trace.go:171","msg":"trace[1435312499] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5261; }","duration":"579.639517ms","start":"2026-04-27T12:06:33.085717Z","end":"2026-04-27T12:06:33.665356Z","steps":["trace[1435312499] 'agreement among raft nodes before linearized reading' (duration: 579.01991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:33.665875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:33.085988Z","time spent":"579.87484ms","remote":"127.0.0.1:45184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-04-27T12:06:33.666015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:33.085692Z","time spent":"580.209606ms","remote":"127.0.0.1:45184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6182,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-04-27T12:06:33.665512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:33.085928Z","time spent":"579.407765ms","remote":"127.0.0.1:45184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-04-27T12:06:33.665653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.441917ms","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-27T12:06:33.666576Z","caller":"traceutil/trace.go:171","msg":"trace[1450775878] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5261; }","duration":"224.39031ms","start":"2026-04-27T12:06:33.442161Z","end":"2026-04-27T12:06:33.666551Z","steps":["trace[1450775878] 'agreement among raft nodes before linearized reading' (duration: 222.887651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:33.665664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:33.122835Z","time spent":"542.817677ms","remote":"127.0.0.1:44930","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-27T12:06:33.665671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.767663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:33.667233Z","caller":"traceutil/trace.go:171","msg":"trace[1496734612] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:5261; }","duration":"404.361623ms","start":"2026-04-27T12:06:33.26285Z","end":"2026-04-27T12:06:33.667212Z","steps":["trace[1496734612] 'agreement among raft nodes before linearized reading' (duration: 402.795703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:33.667297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:33.262827Z","time spent":"404.449303ms","remote":"127.0.0.1:36604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T12:06:34.03599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.343644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:06:34.036122Z","caller":"traceutil/trace.go:171","msg":"trace[86151323] linearizableReadLoop","detail":"{readStateIndex:5460; appliedIndex:5457; }","duration":"328.668419ms","start":"2026-04-27T12:06:33.707443Z","end":"2026-04-27T12:06:34.036111Z","steps":["trace[86151323] 'read index received' (duration: 66.151203ms)","trace[86151323] 'applied index is now lower than readState.Index' (duration: 262.516496ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:06:34.036218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.776701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T12:06:34.036231Z","caller":"traceutil/trace.go:171","msg":"trace[1571510467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5264; }","duration":"328.822941ms","start":"2026-04-27T12:06:33.707403Z","end":"2026-04-27T12:06:34.036226Z","steps":["trace[1571510467] 'agreement among raft nodes before linearized reading' (duration: 328.73477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:34.036248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:33.707385Z","time spent":"328.857671ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T12:06:34.036262Z","caller":"traceutil/trace.go:171","msg":"trace[1197388991] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"366.118705ms","start":"2026-04-27T12:06:33.670113Z","end":"2026-04-27T12:06:34.036231Z","steps":["trace[1197388991] 'process raft request' (duration: 103.471517ms)","trace[1197388991] 'compare' (duration: 262.270923ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:34.036298Z","caller":"traceutil/trace.go:171","msg":"trace[567989790] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"365.507938ms","start":"2026-04-27T12:06:33.670761Z","end":"2026-04-27T12:06:34.036269Z","steps":["trace[567989790] 'process raft request' (duration: 365.291966ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:34.036358Z","caller":"traceutil/trace.go:171","msg":"trace[394869400] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"363.578763ms","start":"2026-04-27T12:06:33.672772Z","end":"2026-04-27T12:06:34.036351Z","steps":["trace[394869400] 'process raft request' (duration: 363.32024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:34.036388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:33.672764Z","time spent":"363.608784ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:06:34.036409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:33.670101Z","time spent":"366.222977ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:06:34.036432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:33.670752Z","time spent":"365.609389ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:06:34.112493Z","caller":"traceutil/trace.go:171","msg":"trace[420087174] transaction","detail":"{read_only:false; response_revision:5265; number_of_response:1; }","duration":"134.406232ms","start":"2026-04-27T12:06:33.978061Z","end":"2026-04-27T12:06:34.112467Z","steps":["trace[420087174] 'process raft request' (duration: 134.24932ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:34.548738Z","caller":"traceutil/trace.go:171","msg":"trace[1864213131] linearizableReadLoop","detail":"{readStateIndex:5462; appliedIndex:5461; }","duration":"440.514377ms","start":"2026-04-27T12:06:34.108173Z","end":"2026-04-27T12:06:34.548687Z","steps":["trace[1864213131] 'read index received' (duration: 4.741061ms)","trace[1864213131] 'applied index is now lower than readState.Index' (duration: 435.772366ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:34.548826Z","caller":"traceutil/trace.go:171","msg":"trace[730740805] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"509.16676ms","start":"2026-04-27T12:06:34.039627Z","end":"2026-04-27T12:06:34.548793Z","steps":["trace[730740805] 'process raft request' (duration: 508.910687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:34.549002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:34.039601Z","time spent":"509.299112ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:06:34.549394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.192566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-27T12:06:34.549456Z","caller":"traceutil/trace.go:171","msg":"trace[1096534100] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5266; }","duration":"441.302157ms","start":"2026-04-27T12:06:34.108131Z","end":"2026-04-27T12:06:34.549433Z","steps":["trace[1096534100] 'agreement among raft nodes before linearized reading' (duration: 440.707539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:34.549743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:34.108114Z","time spent":"441.53904ms","remote":"127.0.0.1:45022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":548,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-27T12:06:34.868576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.647529ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T12:06:34.868675Z","caller":"traceutil/trace.go:171","msg":"trace[1101363447] linearizableReadLoop","detail":"{readStateIndex:5463; appliedIndex:5462; }","duration":"319.847412ms","start":"2026-04-27T12:06:34.548809Z","end":"2026-04-27T12:06:34.868656Z","steps":["trace[1101363447] 'read index received' (duration: 50.97743ms)","trace[1101363447] 'applied index is now lower than readState.Index' (duration: 268.868532ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:06:34.869015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.122486ms","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-27T12:06:34.869079Z","caller":"traceutil/trace.go:171","msg":"trace[379753527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5266; }","duration":"544.242047ms","start":"2026-04-27T12:06:34.324821Z","end":"2026-04-27T12:06:34.869063Z","steps":["trace[379753527] 'agreement among raft nodes before linearized reading' (duration: 544.013374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:34.869124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:34.324799Z","time spent":"544.312958ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T12:06:34.869145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.622844ms","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-27T12:06:34.86922Z","caller":"traceutil/trace.go:171","msg":"trace[425016367] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5266; }","duration":"196.703054ms","start":"2026-04-27T12:06:34.672477Z","end":"2026-04-27T12:06:34.86918Z","steps":["trace[425016367] 'agreement among raft nodes before linearized reading' (duration: 196.546752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:34.86943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.275913ms","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-27T12:06:34.86959Z","caller":"traceutil/trace.go:171","msg":"trace[296333405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5266; }","duration":"546.452934ms","start":"2026-04-27T12:06:34.323071Z","end":"2026-04-27T12:06:34.869524Z","steps":["trace[296333405] 'agreement among raft nodes before linearized reading' (duration: 545.971719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:34.869764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:34.32302Z","time spent":"546.726078ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T12:06:35.044041Z","caller":"traceutil/trace.go:171","msg":"trace[1050371222] transaction","detail":"{read_only:false; response_revision:5268; number_of_response:1; }","duration":"170.51037ms","start":"2026-04-27T12:06:34.873488Z","end":"2026-04-27T12:06:35.043998Z","steps":["trace[1050371222] 'process raft request' (duration: 123.628433ms)","trace[1050371222] 'compare' (duration: 46.770236ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:35.044186Z","caller":"traceutil/trace.go:171","msg":"trace[1945024821] linearizableReadLoop","detail":"{readStateIndex:5466; appliedIndex:5463; }","duration":"164.820478ms","start":"2026-04-27T12:06:34.879348Z","end":"2026-04-27T12:06:35.044168Z","steps":["trace[1945024821] 'read index received' (duration: 36.588086ms)","trace[1945024821] 'applied index is now lower than readState.Index' (duration: 128.231432ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:06:35.044317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.953759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:35.044359Z","caller":"traceutil/trace.go:171","msg":"trace[1008798409] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5269; }","duration":"165.0156ms","start":"2026-04-27T12:06:34.879331Z","end":"2026-04-27T12:06:35.044346Z","steps":["trace[1008798409] 'agreement among raft nodes before linearized reading' (duration: 164.932769ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:35.044446Z","caller":"traceutil/trace.go:171","msg":"trace[984926533] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"170.211246ms","start":"2026-04-27T12:06:34.874063Z","end":"2026-04-27T12:06:35.044274Z","steps":["trace[984926533] 'process raft request' (duration: 170.029954ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:15.779509Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2900} {"level":"info","ts":"2026-04-27T12:07:15.866493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2900,"took":"85.811418ms","hash":2452298574,"current-db-size-bytes":54616064,"current-db-size":"55 MB","current-db-size-in-use-bytes":29687808,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-27T12:07:15.866567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2452298574,"revision":2900,"compact-revision":-1} {"level":"info","ts":"2026-04-27T12:12:15.785324Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5584} {"level":"info","ts":"2026-04-27T12:12:15.907657Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5584,"took":"121.018242ms","hash":1268833564,"current-db-size-bytes":54616064,"current-db-size":"55 MB","current-db-size-in-use-bytes":18378752,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-27T12:12:15.907721Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1268833564,"revision":5584,"compact-revision":2900}