{"level":"warn","ts":"2026-04-24T01:21:55.064924Z","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-24T01:21:55.065024Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-24T01:21:55.0651Z","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-24T01:21:55.065116Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-24T01:21:55.065139Z","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-24T01:21:55.065936Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-04-24T01:21:55.066051Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-24T01:21:55.070229Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.955996ms"} {"level":"info","ts":"2026-04-24T01:21:55.075967Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-04-24T01:21:55.076061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-04-24T01:21:55.076082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-04-24T01:21:55.076093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-24T01:21:55.076102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-04-24T01:21:55.076138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-04-24T01:21:55.082377Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-24T01:21:55.083684Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-24T01:21:55.085246Z","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-24T01:21:55.088177Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-24T01:21:55.088394Z","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-24T01:21:55.088494Z","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-24T01:21:55.088505Z","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-24T01:21:55.088676Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-24T01:21:55.08976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-04-24T01:21:55.090008Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-24T01:21:55.093852Z","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-24T01:21:55.093963Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-24T01:21:55.094039Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-24T01:21:55.096317Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-24T01:21:55.096364Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-24T01:21:55.376442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-04-24T01:21:55.376509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-24T01:21:55.376546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-04-24T01:21:55.37657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-04-24T01:21:55.376584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-24T01:21:55.376604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-04-24T01:21:55.376621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-24T01:21:55.377576Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T01:21:55.378537Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-04-24T01:21:55.378577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T01:21:55.378702Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T01:21:55.378869Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-24T01:21:55.378903Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-24T01:21:55.378959Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T01:21:55.37912Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T01:21:55.37917Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T01:21:55.382041Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-04-24T01:21:55.382049Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-24T01:22:23.822171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.408907ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-24T01:22:24.688618Z","caller":"traceutil/trace.go:171","msg":"trace[362673793] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"301.797604ms","start":"2026-04-24T01:22:24.386796Z","end":"2026-04-24T01:22:24.688594Z","steps":["trace[362673793] 'read index received' (duration: 301.05098ms)","trace[362673793] 'applied index is now lower than readState.Index' (duration: 745.384µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:22:24.688888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.06685ms","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-24T01:22:24.688939Z","caller":"traceutil/trace.go:171","msg":"trace[2078650945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"302.163462ms","start":"2026-04-24T01:22:24.386759Z","end":"2026-04-24T01:22:24.688923Z","steps":["trace[2078650945] 'agreement among raft nodes before linearized reading' (duration: 301.953217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:22:24.689009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:22:24.386741Z","time spent":"302.254363ms","remote":"127.0.0.1:37082","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-24T01:22:24.689361Z","caller":"traceutil/trace.go:171","msg":"trace[1159863404] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"342.237272ms","start":"2026-04-24T01:22:24.347104Z","end":"2026-04-24T01:22:24.689341Z","steps":["trace[1159863404] 'process raft request' (duration: 340.988357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:22:24.690688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:22:24.347079Z","time spent":"342.324274ms","remote":"127.0.0.1:36886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036169,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:22:25.016867Z","caller":"traceutil/trace.go:171","msg":"trace[142903761] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"168.04445ms","start":"2026-04-24T01:22:24.848802Z","end":"2026-04-24T01:22:25.016846Z","steps":["trace[142903761] 'read index received' (duration: 167.867666ms)","trace[142903761] 'applied index is now lower than readState.Index' (duration: 175.954µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:22:25.016978Z","caller":"traceutil/trace.go:171","msg":"trace[159597863] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"251.142406ms","start":"2026-04-24T01:22:24.765806Z","end":"2026-04-24T01:22:25.016949Z","steps":["trace[159597863] 'process raft request' (duration: 250.913681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:22:25.017573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.738532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:22:25.017696Z","caller":"traceutil/trace.go:171","msg":"trace[962936895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"168.877535ms","start":"2026-04-24T01:22:24.848766Z","end":"2026-04-24T01:22:25.017644Z","steps":["trace[962936895] 'agreement among raft nodes before linearized reading' (duration: 168.229662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:22:25.018857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.134458ms","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-24T01:22:25.018917Z","caller":"traceutil/trace.go:171","msg":"trace[709392321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"122.206869ms","start":"2026-04-24T01:22:24.896681Z","end":"2026-04-24T01:22:25.018888Z","steps":["trace[709392321] 'agreement among raft nodes before linearized reading' (duration: 122.038576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:22:25.019831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.313661ms","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-24T01:22:25.019907Z","caller":"traceutil/trace.go:171","msg":"trace[698494569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"123.416562ms","start":"2026-04-24T01:22:24.896458Z","end":"2026-04-24T01:22:25.019874Z","steps":["trace[698494569] 'agreement among raft nodes before linearized reading' (duration: 123.241959ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:22:25.189083Z","caller":"traceutil/trace.go:171","msg":"trace[1642328047] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"163.411571ms","start":"2026-04-24T01:22:25.025645Z","end":"2026-04-24T01:22:25.189057Z","steps":["trace[1642328047] 'process raft request' (duration: 146.769813ms)","trace[1642328047] 'compare' (duration: 16.458815ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:22:25.189481Z","caller":"traceutil/trace.go:171","msg":"trace[1765647254] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"158.167331ms","start":"2026-04-24T01:22:25.031257Z","end":"2026-04-24T01:22:25.189424Z","steps":["trace[1765647254] 'read index received' (duration: 141.278269ms)","trace[1765647254] 'applied index is now lower than readState.Index' (duration: 16.888292ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:22:25.189617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.335064ms","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":"info","ts":"2026-04-24T01:22:25.18965Z","caller":"traceutil/trace.go:171","msg":"trace[1917899627] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:604; }","duration":"158.399505ms","start":"2026-04-24T01:22:25.031238Z","end":"2026-04-24T01:22:25.189638Z","steps":["trace[1917899627] 'agreement among raft nodes before linearized reading' (duration: 158.327814ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:22:25.189705Z","caller":"traceutil/trace.go:171","msg":"trace[789345089] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"163.573904ms","start":"2026-04-24T01:22:25.026115Z","end":"2026-04-24T01:22:25.189689Z","steps":["trace[789345089] 'process raft request' (duration: 162.900012ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:22:25.189884Z","caller":"traceutil/trace.go:171","msg":"trace[676287326] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"156.934658ms","start":"2026-04-24T01:22:25.032938Z","end":"2026-04-24T01:22:25.189873Z","steps":["trace[676287326] 'process raft request' (duration: 156.394447ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:22:42.162711Z","caller":"traceutil/trace.go:171","msg":"trace[150346037] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"107.203182ms","start":"2026-04-24T01:22:42.055487Z","end":"2026-04-24T01:22:42.16269Z","steps":["trace[150346037] 'process raft request' (duration: 107.040059ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:24:41.202928Z","caller":"traceutil/trace.go:171","msg":"trace[542189551] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"146.315488ms","start":"2026-04-24T01:24:41.056579Z","end":"2026-04-24T01:24:41.202895Z","steps":["trace[542189551] 'process raft request' (duration: 146.100002ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:10.817262Z","caller":"traceutil/trace.go:171","msg":"trace[340169968] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"129.981179ms","start":"2026-04-24T01:25:10.687247Z","end":"2026-04-24T01:25:10.817229Z","steps":["trace[340169968] 'process raft request' (duration: 129.928548ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:10.817494Z","caller":"traceutil/trace.go:171","msg":"trace[451414072] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"132.173569ms","start":"2026-04-24T01:25:10.685288Z","end":"2026-04-24T01:25:10.817461Z","steps":["trace[451414072] 'process raft request' (duration: 127.509843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:10.817736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.621165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-82m68\" ","response":"range_response_count:1 size:4562"} {"level":"info","ts":"2026-04-24T01:25:10.817777Z","caller":"traceutil/trace.go:171","msg":"trace[459841502] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"129.090076ms","start":"2026-04-24T01:25:10.688667Z","end":"2026-04-24T01:25:10.817757Z","steps":["trace[459841502] 'process raft request' (duration: 128.541801ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:10.817808Z","caller":"traceutil/trace.go:171","msg":"trace[1676537182] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"127.384719ms","start":"2026-04-24T01:25:10.690419Z","end":"2026-04-24T01:25:10.817804Z","steps":["trace[1676537182] 'process raft request' (duration: 127.122682ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:10.817788Z","caller":"traceutil/trace.go:171","msg":"trace[1771795228] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-82m68; range_end:; response_count:1; response_revision:1951; }","duration":"134.978165ms","start":"2026-04-24T01:25:10.682801Z","end":"2026-04-24T01:25:10.817779Z","steps":["trace[1771795228] 'agreement among raft nodes before linearized reading' (duration: 134.829821ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:10.817933Z","caller":"traceutil/trace.go:171","msg":"trace[1552013844] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"123.955417ms","start":"2026-04-24T01:25:10.693923Z","end":"2026-04-24T01:25:10.817878Z","steps":["trace[1552013844] 'process raft request' (duration: 123.646529ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:10.818035Z","caller":"traceutil/trace.go:171","msg":"trace[223822699] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"128.922131ms","start":"2026-04-24T01:25:10.689103Z","end":"2026-04-24T01:25:10.818025Z","steps":["trace[223822699] 'process raft request' (duration: 128.373087ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:10.818079Z","caller":"traceutil/trace.go:171","msg":"trace[1772621653] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"119.847576ms","start":"2026-04-24T01:25:10.698226Z","end":"2026-04-24T01:25:10.818074Z","steps":["trace[1772621653] 'process raft request' (duration: 119.370234ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:11.190833Z","caller":"traceutil/trace.go:171","msg":"trace[560066890] linearizableReadLoop","detail":"{readStateIndex:2045; appliedIndex:2044; }","duration":"114.470892ms","start":"2026-04-24T01:25:11.076341Z","end":"2026-04-24T01:25:11.190812Z","steps":["trace[560066890] 'read index received' (duration: 114.356449ms)","trace[560066890] 'applied index is now lower than readState.Index' (duration: 113.573µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:25:11.190901Z","caller":"traceutil/trace.go:171","msg":"trace[1468813683] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"123.978979ms","start":"2026-04-24T01:25:11.066895Z","end":"2026-04-24T01:25:11.190874Z","steps":["trace[1468813683] 'process raft request' (duration: 123.800694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:11.191182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.7617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:3 size:228215"} {"level":"info","ts":"2026-04-24T01:25:11.191235Z","caller":"traceutil/trace.go:171","msg":"trace[1408909918] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:3; response_revision:1961; }","duration":"114.888403ms","start":"2026-04-24T01:25:11.076337Z","end":"2026-04-24T01:25:11.191225Z","steps":["trace[1408909918] 'agreement among raft nodes before linearized reading' (duration: 114.557084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:27.049306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.536802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-266b59b9-54dc-4729-91f0-614ed2d66183\" ","response":"range_response_count:1 size:3735"} {"level":"warn","ts":"2026-04-24T01:25:27.049298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.626076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:25:27.049425Z","caller":"traceutil/trace.go:171","msg":"trace[1821673955] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-266b59b9-54dc-4729-91f0-614ed2d66183; range_end:; response_count:1; response_revision:2264; }","duration":"176.701517ms","start":"2026-04-24T01:25:26.872699Z","end":"2026-04-24T01:25:27.0494Z","steps":["trace[1821673955] 'range keys from in-memory index tree' (duration: 176.4425ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:27.049455Z","caller":"traceutil/trace.go:171","msg":"trace[600205929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2264; }","duration":"200.811721ms","start":"2026-04-24T01:25:26.848611Z","end":"2026-04-24T01:25:27.049423Z","steps":["trace[600205929] 'range keys from in-memory index tree' (duration: 200.560544ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:27.950899Z","caller":"traceutil/trace.go:171","msg":"trace[739953158] linearizableReadLoop","detail":"{readStateIndex:2361; appliedIndex:2360; }","duration":"307.553431ms","start":"2026-04-24T01:25:27.643316Z","end":"2026-04-24T01:25:27.950869Z","steps":["trace[739953158] 'read index received' (duration: 307.50595ms)","trace[739953158] 'applied index is now lower than readState.Index' (duration: 46.131µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:25:27.951014Z","caller":"traceutil/trace.go:171","msg":"trace[900189258] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"312.713111ms","start":"2026-04-24T01:25:27.638266Z","end":"2026-04-24T01:25:27.950979Z","steps":["trace[900189258] 'process raft request' (duration: 312.385882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:27.95122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:27.63825Z","time spent":"312.882855ms","remote":"127.0.0.1:37082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:25:27.951389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.043874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-24T01:25:27.95147Z","caller":"traceutil/trace.go:171","msg":"trace[1333213936] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2267; }","duration":"308.167177ms","start":"2026-04-24T01:25:27.643281Z","end":"2026-04-24T01:25:27.951449Z","steps":["trace[1333213936] 'agreement among raft nodes before linearized reading' (duration: 307.744206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:27.951518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:27.643265Z","time spent":"308.243359ms","remote":"127.0.0.1:43904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-24T01:25:28.356697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.159196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:25:28.357044Z","caller":"traceutil/trace.go:171","msg":"trace[170323703] linearizableReadLoop","detail":"{readStateIndex:2362; appliedIndex:2361; }","duration":"406.029677ms","start":"2026-04-24T01:25:27.95099Z","end":"2026-04-24T01:25:28.357019Z","steps":["trace[170323703] 'read index received' (duration: 247.42862ms)","trace[170323703] 'applied index is now lower than readState.Index' (duration: 158.599347ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:25:28.357088Z","caller":"traceutil/trace.go:171","msg":"trace[73074704] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"202.607412ms","start":"2026-04-24T01:25:28.154425Z","end":"2026-04-24T01:25:28.357033Z","steps":["trace[73074704] 'process raft request' (duration: 202.450178ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:25:28.357185Z","caller":"traceutil/trace.go:171","msg":"trace[1034961509] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"623.764239ms","start":"2026-04-24T01:25:27.733206Z","end":"2026-04-24T01:25:28.35697Z","steps":["trace[1034961509] 'process raft request' (duration: 465.199582ms)","trace[1034961509] 'compare' (duration: 157.659363ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:25:28.35779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:27.733181Z","time spent":"624.53722ms","remote":"127.0.0.1:37082","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-24T01:25:28.357227Z","caller":"traceutil/trace.go:171","msg":"trace[2040725151] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"193.365361ms","start":"2026-04-24T01:25:28.163838Z","end":"2026-04-24T01:25:28.357204Z","steps":["trace[2040725151] 'process raft request' (duration: 193.154035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:28.357573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.392977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2cb8266-394d-439e-bd09-2d11376c0a29\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-04-24T01:25:28.358618Z","caller":"traceutil/trace.go:171","msg":"trace[688980080] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c2cb8266-394d-439e-bd09-2d11376c0a29; range_end:; response_count:1; response_revision:2270; }","duration":"582.487698ms","start":"2026-04-24T01:25:27.776114Z","end":"2026-04-24T01:25:28.358602Z","steps":["trace[688980080] 'agreement among raft nodes before linearized reading' (duration: 580.993606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:28.358725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:27.776094Z","time spent":"582.56207ms","remote":"127.0.0.1:36984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2cb8266-394d-439e-bd09-2d11376c0a29\" "} {"level":"warn","ts":"2026-04-24T01:25:28.357594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.875123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-266b59b9-54dc-4729-91f0-614ed2d66183\" ","response":"range_response_count:1 size:3735"} {"level":"info","ts":"2026-04-24T01:25:28.358843Z","caller":"traceutil/trace.go:171","msg":"trace[833313751] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-266b59b9-54dc-4729-91f0-614ed2d66183; range_end:; response_count:1; response_revision:2270; }","duration":"305.21121ms","start":"2026-04-24T01:25:28.053617Z","end":"2026-04-24T01:25:28.358828Z","steps":["trace[833313751] 'agreement among raft nodes before linearized reading' (duration: 303.817011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:28.358969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:28.053598Z","time spent":"305.320362ms","remote":"127.0.0.1:36984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3758,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-266b59b9-54dc-4729-91f0-614ed2d66183\" "} {"level":"info","ts":"2026-04-24T01:25:47.960704Z","caller":"traceutil/trace.go:171","msg":"trace[1657925988] linearizableReadLoop","detail":"{readStateIndex:2561; appliedIndex:2560; }","duration":"114.132644ms","start":"2026-04-24T01:25:47.846541Z","end":"2026-04-24T01:25:47.960674Z","steps":["trace[1657925988] 'read index received' (duration: 113.913598ms)","trace[1657925988] 'applied index is now lower than readState.Index' (duration: 217.776µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:25:47.960804Z","caller":"traceutil/trace.go:171","msg":"trace[859943978] transaction","detail":"{read_only:false; response_revision:2460; number_of_response:1; }","duration":"341.532518ms","start":"2026-04-24T01:25:47.619244Z","end":"2026-04-24T01:25:47.960776Z","steps":["trace[859943978] 'process raft request' (duration: 341.286731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:47.960834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.288798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:25:47.96088Z","caller":"traceutil/trace.go:171","msg":"trace[179669664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2460; }","duration":"114.376521ms","start":"2026-04-24T01:25:47.846492Z","end":"2026-04-24T01:25:47.960868Z","steps":["trace[179669664] 'agreement among raft nodes before linearized reading' (duration: 114.299488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:25:47.960931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:25:47.619228Z","time spent":"341.63396ms","remote":"127.0.0.1:37082","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-24T01:26:02.446854Z","caller":"traceutil/trace.go:171","msg":"trace[1038700441] transaction","detail":"{read_only:false; response_revision:2543; number_of_response:1; }","duration":"242.858764ms","start":"2026-04-24T01:26:02.203935Z","end":"2026-04-24T01:26:02.446794Z","steps":["trace[1038700441] 'process raft request' (duration: 242.733201ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:02.761495Z","caller":"traceutil/trace.go:171","msg":"trace[1009073427] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"236.90551ms","start":"2026-04-24T01:26:02.524564Z","end":"2026-04-24T01:26:02.76147Z","steps":["trace[1009073427] 'process raft request' (duration: 236.758906ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:02.784277Z","caller":"traceutil/trace.go:171","msg":"trace[1077543512] transaction","detail":"{read_only:false; response_revision:2546; number_of_response:1; }","duration":"258.07063ms","start":"2026-04-24T01:26:02.526191Z","end":"2026-04-24T01:26:02.784261Z","steps":["trace[1077543512] 'process raft request' (duration: 257.989427ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:02.784318Z","caller":"traceutil/trace.go:171","msg":"trace[48024590] transaction","detail":"{read_only:false; response_revision:2545; number_of_response:1; }","duration":"258.510472ms","start":"2026-04-24T01:26:02.525783Z","end":"2026-04-24T01:26:02.784294Z","steps":["trace[48024590] 'process raft request' (duration: 257.235527ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:02.784422Z","caller":"traceutil/trace.go:171","msg":"trace[1056476490] linearizableReadLoop","detail":"{readStateIndex:2650; appliedIndex:2647; }","duration":"245.975329ms","start":"2026-04-24T01:26:02.538437Z","end":"2026-04-24T01:26:02.784412Z","steps":["trace[1056476490] 'read index received' (duration: 222.830366ms)","trace[1056476490] 'applied index is now lower than readState.Index' (duration: 23.144043ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:26:02.784499Z","caller":"traceutil/trace.go:171","msg":"trace[1073954997] transaction","detail":"{read_only:false; response_revision:2547; number_of_response:1; }","duration":"141.971913ms","start":"2026-04-24T01:26:02.642519Z","end":"2026-04-24T01:26:02.784491Z","steps":["trace[1073954997] 'process raft request' (duration: 141.709496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:02.784659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.220922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-24T01:26:02.784728Z","caller":"traceutil/trace.go:171","msg":"trace[1760422070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2547; }","duration":"244.321375ms","start":"2026-04-24T01:26:02.540389Z","end":"2026-04-24T01:26:02.784711Z","steps":["trace[1760422070] 'agreement among raft nodes before linearized reading' (duration: 244.148989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:02.784788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.564313ms","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-24T01:26:02.784831Z","caller":"traceutil/trace.go:171","msg":"trace[1844362318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2547; }","duration":"122.656326ms","start":"2026-04-24T01:26:02.662163Z","end":"2026-04-24T01:26:02.78482Z","steps":["trace[1844362318] 'agreement among raft nodes before linearized reading' (duration: 122.467101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:02.784909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.988705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-24T01:26:02.784947Z","caller":"traceutil/trace.go:171","msg":"trace[1104474446] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2547; }","duration":"195.051917ms","start":"2026-04-24T01:26:02.58988Z","end":"2026-04-24T01:26:02.784932Z","steps":["trace[1104474446] 'agreement among raft nodes before linearized reading' (duration: 194.952144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:02.78501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.566646ms","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-24T01:26:02.785054Z","caller":"traceutil/trace.go:171","msg":"trace[1451312113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2547; }","duration":"246.630117ms","start":"2026-04-24T01:26:02.538411Z","end":"2026-04-24T01:26:02.785041Z","steps":["trace[1451312113] 'agreement among raft nodes before linearized reading' (duration: 246.524444ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:02.947057Z","caller":"traceutil/trace.go:171","msg":"trace[2025373262] transaction","detail":"{read_only:false; response_revision:2548; number_of_response:1; }","duration":"157.796927ms","start":"2026-04-24T01:26:02.789242Z","end":"2026-04-24T01:26:02.947039Z","steps":["trace[2025373262] 'process raft request' (duration: 71.463456ms)","trace[2025373262] 'compare' (duration: 86.062813ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:26:02.980222Z","caller":"traceutil/trace.go:171","msg":"trace[2062075100] linearizableReadLoop","detail":"{readStateIndex:2654; appliedIndex:2651; }","duration":"132.433543ms","start":"2026-04-24T01:26:02.847771Z","end":"2026-04-24T01:26:02.980204Z","steps":["trace[2062075100] 'read index received' (duration: 12.949325ms)","trace[2062075100] 'applied index is now lower than readState.Index' (duration: 119.483298ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:26:02.980343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.569747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:26:02.980314Z","caller":"traceutil/trace.go:171","msg":"trace[1828497989] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"190.478061ms","start":"2026-04-24T01:26:02.789802Z","end":"2026-04-24T01:26:02.98028Z","steps":["trace[1828497989] 'process raft request' (duration: 190.312707ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:02.980366Z","caller":"traceutil/trace.go:171","msg":"trace[792879036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2550; }","duration":"132.637689ms","start":"2026-04-24T01:26:02.84772Z","end":"2026-04-24T01:26:02.980358Z","steps":["trace[792879036] 'agreement among raft nodes before linearized reading' (duration: 132.543746ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:02.980338Z","caller":"traceutil/trace.go:171","msg":"trace[1666635025] transaction","detail":"{read_only:false; response_revision:2550; number_of_response:1; }","duration":"190.002167ms","start":"2026-04-24T01:26:02.790305Z","end":"2026-04-24T01:26:02.980307Z","steps":["trace[1666635025] 'process raft request' (duration: 189.880154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:03.774625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.238352ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-24T01:26:04.223435Z","caller":"traceutil/trace.go:171","msg":"trace[673927618] transaction","detail":"{read_only:false; response_revision:2552; number_of_response:1; }","duration":"158.954026ms","start":"2026-04-24T01:26:04.064408Z","end":"2026-04-24T01:26:04.223362Z","steps":["trace[673927618] 'process raft request' (duration: 158.782524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:04.772602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.892575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:26:04.772658Z","caller":"traceutil/trace.go:171","msg":"trace[1169877811] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2552; }","duration":"195.986686ms","start":"2026-04-24T01:26:04.576659Z","end":"2026-04-24T01:26:04.772646Z","steps":["trace[1169877811] 'count revisions from in-memory index tree' (duration: 195.826593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:04.772665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.396629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:26:04.772763Z","caller":"traceutil/trace.go:171","msg":"trace[1319494290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2552; }","duration":"270.534711ms","start":"2026-04-24T01:26:04.50221Z","end":"2026-04-24T01:26:04.772745Z","steps":["trace[1319494290] 'range keys from in-memory index tree' (duration: 270.295378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:04.772924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.256435ms","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-24T01:26:04.772993Z","caller":"traceutil/trace.go:171","msg":"trace[241242996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2552; }","duration":"322.359607ms","start":"2026-04-24T01:26:04.450615Z","end":"2026-04-24T01:26:04.772975Z","steps":["trace[241242996] 'range keys from in-memory index tree' (duration: 322.086543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:04.77303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:04.4506Z","time spent":"322.421658ms","remote":"127.0.0.1:37082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-24T01:26:04.908688Z","caller":"traceutil/trace.go:171","msg":"trace[1632683221] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"264.400023ms","start":"2026-04-24T01:26:04.644261Z","end":"2026-04-24T01:26:04.908661Z","steps":["trace[1632683221] 'process raft request' (duration: 264.236551ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:04.925097Z","caller":"traceutil/trace.go:171","msg":"trace[1062757639] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"159.468494ms","start":"2026-04-24T01:26:04.765591Z","end":"2026-04-24T01:26:04.92506Z","steps":["trace[1062757639] 'process raft request' (duration: 159.382593ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:04.925114Z","caller":"traceutil/trace.go:171","msg":"trace[585219374] transaction","detail":"{read_only:false; response_revision:2556; number_of_response:1; }","duration":"147.418339ms","start":"2026-04-24T01:26:04.777671Z","end":"2026-04-24T01:26:04.925089Z","steps":["trace[585219374] 'process raft request' (duration: 147.334387ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:04.925122Z","caller":"traceutil/trace.go:171","msg":"trace[1533098387] linearizableReadLoop","detail":"{readStateIndex:2664; appliedIndex:2657; }","duration":"126.691068ms","start":"2026-04-24T01:26:04.798397Z","end":"2026-04-24T01:26:04.925088Z","steps":["trace[1533098387] 'read index received' (duration: 110.114076ms)","trace[1533098387] 'applied index is now lower than readState.Index' (duration: 16.576172ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:26:04.925097Z","caller":"traceutil/trace.go:171","msg":"trace[1465974978] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"190.869352ms","start":"2026-04-24T01:26:04.734181Z","end":"2026-04-24T01:26:04.92505Z","steps":["trace[1465974978] 'process raft request' (duration: 190.657339ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:04.925202Z","caller":"traceutil/trace.go:171","msg":"trace[425693727] transaction","detail":"{read_only:false; response_revision:2557; number_of_response:1; }","duration":"134.895126ms","start":"2026-04-24T01:26:04.790299Z","end":"2026-04-24T01:26:04.925194Z","steps":["trace[425693727] 'process raft request' (duration: 134.729944ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:04.925349Z","caller":"traceutil/trace.go:171","msg":"trace[1602407693] transaction","detail":"{read_only:false; response_revision:2558; number_of_response:1; }","duration":"134.901675ms","start":"2026-04-24T01:26:04.790439Z","end":"2026-04-24T01:26:04.925341Z","steps":["trace[1602407693] 'process raft request' (duration: 134.609061ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:04.92544Z","caller":"traceutil/trace.go:171","msg":"trace[1378698731] transaction","detail":"{read_only:false; response_revision:2559; number_of_response:1; }","duration":"133.723508ms","start":"2026-04-24T01:26:04.791711Z","end":"2026-04-24T01:26:04.925434Z","steps":["trace[1378698731] 'process raft request' (duration: 133.355853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:04.925449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.043052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-24T01:26:04.925487Z","caller":"traceutil/trace.go:171","msg":"trace[359999314] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2559; }","duration":"127.091123ms","start":"2026-04-24T01:26:04.798384Z","end":"2026-04-24T01:26:04.925475Z","steps":["trace[359999314] 'agreement among raft nodes before linearized reading' (duration: 126.89678ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:30.485875Z","caller":"traceutil/trace.go:171","msg":"trace[1323861250] transaction","detail":"{read_only:false; response_revision:2709; number_of_response:1; }","duration":"102.94601ms","start":"2026-04-24T01:26:30.382904Z","end":"2026-04-24T01:26:30.48585Z","steps":["trace[1323861250] 'process raft request' (duration: 102.703036ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:30.769117Z","caller":"traceutil/trace.go:171","msg":"trace[1493327757] transaction","detail":"{read_only:false; response_revision:2710; number_of_response:1; }","duration":"129.795289ms","start":"2026-04-24T01:26:30.639301Z","end":"2026-04-24T01:26:30.769096Z","steps":["trace[1493327757] 'process raft request' (duration: 59.165029ms)","trace[1493327757] 'compare' (duration: 70.526718ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:26:36.782068Z","caller":"traceutil/trace.go:171","msg":"trace[1837323681] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"240.636897ms","start":"2026-04-24T01:26:36.541407Z","end":"2026-04-24T01:26:36.782044Z","steps":["trace[1837323681] 'process raft request' (duration: 240.432843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:37.532574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.081513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T01:26:37.532833Z","caller":"traceutil/trace.go:171","msg":"trace[203406188] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2745; }","duration":"485.353489ms","start":"2026-04-24T01:26:37.047435Z","end":"2026-04-24T01:26:37.532788Z","steps":["trace[203406188] 'count revisions from in-memory index tree' (duration: 485.000191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:37.53299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.047409Z","time spent":"485.563852ms","remote":"127.0.0.1:43880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-24T01:26:37.532559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.818085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:26:37.533355Z","caller":"traceutil/trace.go:171","msg":"trace[222984701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2745; }","duration":"687.677581ms","start":"2026-04-24T01:26:36.845665Z","end":"2026-04-24T01:26:37.533343Z","steps":["trace[222984701] 'range keys from in-memory index tree' (duration: 686.684464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:37.533442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:36.845648Z","time spent":"687.782692ms","remote":"127.0.0.1:36810","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-24T01:26:37.533324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.288275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:26:37.533722Z","caller":"traceutil/trace.go:171","msg":"trace[1098771799] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"448.466912ms","start":"2026-04-24T01:26:37.085236Z","end":"2026-04-24T01:26:37.533703Z","steps":["trace[1098771799] 'process raft request' (duration: 188.710959ms)","trace[1098771799] 'compare' (duration: 259.033371ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:26:37.533872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.085212Z","time spent":"448.594835ms","remote":"127.0.0.1:37082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:26:37.923947Z","caller":"traceutil/trace.go:171","msg":"trace[1328971922] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"800.959432ms","start":"2026-04-24T01:26:37.122925Z","end":"2026-04-24T01:26:37.923884Z","steps":["trace[1328971922] 'process raft request' (duration: 708.326047ms)","trace[1328971922] 'compare' (duration: 92.250009ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:26:37.924243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.122891Z","time spent":"801.275667ms","remote":"127.0.0.1:37082","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-24T01:26:37.924291Z","caller":"traceutil/trace.go:171","msg":"trace[654050036] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"747.649724ms","start":"2026-04-24T01:26:37.176625Z","end":"2026-04-24T01:26:37.924275Z","steps":["trace[654050036] 'process raft request' (duration: 747.188526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:37.924522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.176599Z","time spent":"747.823308ms","remote":"127.0.0.1:37082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:26:37.924766Z","caller":"traceutil/trace.go:171","msg":"trace[1846196726] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"739.005564ms","start":"2026-04-24T01:26:37.185742Z","end":"2026-04-24T01:26:37.924747Z","steps":["trace[1846196726] 'process raft request' (duration: 738.450055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:37.92483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.185715Z","time spent":"739.083857ms","remote":"127.0.0.1:37082","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-24T01:26:37.92472Z","caller":"traceutil/trace.go:171","msg":"trace[1618006529] linearizableReadLoop","detail":"{readStateIndex:2860; appliedIndex:2856; }","duration":"730.921765ms","start":"2026-04-24T01:26:37.193748Z","end":"2026-04-24T01:26:37.92467Z","steps":["trace[1618006529] 'read index received' (duration: 80.20882ms)","trace[1618006529] 'applied index is now lower than readState.Index' (duration: 650.710595ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:26:37.925135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.371143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-24T01:26:37.925212Z","caller":"traceutil/trace.go:171","msg":"trace[1487562836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2749; }","duration":"731.484236ms","start":"2026-04-24T01:26:37.193707Z","end":"2026-04-24T01:26:37.925191Z","steps":["trace[1487562836] 'agreement among raft nodes before linearized reading' (duration: 731.262992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:37.925259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.193691Z","time spent":"731.559807ms","remote":"127.0.0.1:37082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-24T01:26:37.925664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.327873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-24T01:26:37.925684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.105406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:26:37.925723Z","caller":"traceutil/trace.go:171","msg":"trace[691061056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2749; }","duration":"391.189276ms","start":"2026-04-24T01:26:37.534522Z","end":"2026-04-24T01:26:37.925711Z","steps":["trace[691061056] 'agreement among raft nodes before linearized reading' (duration: 391.103555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:37.925751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.534507Z","time spent":"391.237897ms","remote":"127.0.0.1:36802","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-24T01:26:37.92585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.93843ms","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-24T01:26:37.925887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.204639ms","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-24T01:26:37.925992Z","caller":"traceutil/trace.go:171","msg":"trace[1180268819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2749; }","duration":"705.063201ms","start":"2026-04-24T01:26:37.220849Z","end":"2026-04-24T01:26:37.925913Z","steps":["trace[1180268819] 'agreement among raft nodes before linearized reading' (duration: 704.780826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:37.926051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.97228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T01:26:37.926098Z","caller":"traceutil/trace.go:171","msg":"trace[1875400061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2749; }","duration":"696.319761ms","start":"2026-04-24T01:26:37.229612Z","end":"2026-04-24T01:26:37.925932Z","steps":["trace[1875400061] 'agreement among raft nodes before linearized reading' (duration: 696.081817ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:37.92611Z","caller":"traceutil/trace.go:171","msg":"trace[198375162] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2749; }","duration":"230.063592ms","start":"2026-04-24T01:26:37.696031Z","end":"2026-04-24T01:26:37.926095Z","steps":["trace[198375162] 'agreement among raft nodes before linearized reading' (duration: 229.886719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:37.926087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.220807Z","time spent":"705.262374ms","remote":"127.0.0.1:37082","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-24T01:26:37.926181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.229602Z","time spent":"696.563735ms","remote":"127.0.0.1:37082","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-24T01:26:37.926402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.183835ms","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-24T01:26:37.926453Z","caller":"traceutil/trace.go:171","msg":"trace[401172082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2749; }","duration":"719.247436ms","start":"2026-04-24T01:26:37.207193Z","end":"2026-04-24T01:26:37.92644Z","steps":["trace[401172082] 'agreement among raft nodes before linearized reading' (duration: 719.125674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:37.926485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.207186Z","time spent":"719.292526ms","remote":"127.0.0.1:37082","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-24T01:26:37.925713Z","caller":"traceutil/trace.go:171","msg":"trace[1677546061] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2749; }","duration":"731.436535ms","start":"2026-04-24T01:26:37.194257Z","end":"2026-04-24T01:26:37.925701Z","steps":["trace[1677546061] 'agreement among raft nodes before linearized reading' (duration: 731.242572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:37.927653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.194238Z","time spent":"733.395679ms","remote":"127.0.0.1:43904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-24T01:26:38.300609Z","caller":"traceutil/trace.go:171","msg":"trace[259098957] linearizableReadLoop","detail":"{readStateIndex:2864; appliedIndex:2860; }","duration":"365.079038ms","start":"2026-04-24T01:26:37.935509Z","end":"2026-04-24T01:26:38.300588Z","steps":["trace[259098957] 'read index received' (duration: 68.48943ms)","trace[259098957] 'applied index is now lower than readState.Index' (duration: 296.588788ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:26:38.300709Z","caller":"traceutil/trace.go:171","msg":"trace[1308263432] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"369.566845ms","start":"2026-04-24T01:26:37.931119Z","end":"2026-04-24T01:26:38.300686Z","steps":["trace[1308263432] 'process raft request' (duration: 369.26125ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:26:38.300771Z","caller":"traceutil/trace.go:171","msg":"trace[1617361406] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"369.127298ms","start":"2026-04-24T01:26:37.931631Z","end":"2026-04-24T01:26:38.300758Z","steps":["trace[1617361406] 'process raft request' (duration: 368.921834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:38.30089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.931104Z","time spent":"369.693387ms","remote":"127.0.0.1:37082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:26:38.300917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.384753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:26:38.300685Z","caller":"traceutil/trace.go:171","msg":"trace[541201188] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"369.214089ms","start":"2026-04-24T01:26:37.931429Z","end":"2026-04-24T01:26:38.300643Z","steps":["trace[541201188] 'process raft request' (duration: 369.078746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:38.300966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.931622Z","time spent":"369.196029ms","remote":"127.0.0.1:37082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:26:38.301098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.931417Z","time spent":"369.639776ms","remote":"127.0.0.1:37082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T01:26:38.301023Z","caller":"traceutil/trace.go:171","msg":"trace[361163427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2753; }","duration":"365.518226ms","start":"2026-04-24T01:26:37.935493Z","end":"2026-04-24T01:26:38.301011Z","steps":["trace[361163427] 'agreement among raft nodes before linearized reading' (duration: 365.372033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:38.301235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:37.935487Z","time spent":"365.730349ms","remote":"127.0.0.1:36810","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-24T01:26:38.512456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.104822ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-24T01:26:38.512764Z","caller":"traceutil/trace.go:171","msg":"trace[84429745] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"118.532009ms","start":"2026-04-24T01:26:38.394213Z","end":"2026-04-24T01:26:38.512745Z","steps":["trace[84429745] 'process raft request' (duration: 118.355956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:26:38.888649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.614691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T01:26:38.8889Z","caller":"traceutil/trace.go:171","msg":"trace[1954689908] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"375.340779ms","start":"2026-04-24T01:26:38.513516Z","end":"2026-04-24T01:26:38.888857Z","steps":["trace[1954689908] 'process raft request' (duration: 127.437653ms)","trace[1954689908] 'compare' (duration: 247.470878ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:26:38.888782Z","caller":"traceutil/trace.go:171","msg":"trace[1579195811] linearizableReadLoop","detail":"{readStateIndex:2867; appliedIndex:2866; }","duration":"102.33497ms","start":"2026-04-24T01:26:38.786426Z","end":"2026-04-24T01:26:38.888761Z","steps":["trace[1579195811] 'read index received' (duration: 40.121µs)","trace[1579195811] 'applied index is now lower than readState.Index' (duration: 102.293099ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T01:26:38.889372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T01:26:38.513502Z","time spent":"375.614844ms","remote":"127.0.0.1:36824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T01:26:38.88941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.9653ms","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-24T01:26:38.889567Z","caller":"traceutil/trace.go:171","msg":"trace[1769742004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2755; }","duration":"103.175104ms","start":"2026-04-24T01:26:38.786375Z","end":"2026-04-24T01:26:38.88955Z","steps":["trace[1769742004] 'agreement among raft nodes before linearized reading' (duration: 102.812548ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:27:34.339925Z","caller":"traceutil/trace.go:171","msg":"trace[1505404139] transaction","detail":"{read_only:false; response_revision:3106; number_of_response:1; }","duration":"151.342885ms","start":"2026-04-24T01:27:34.18855Z","end":"2026-04-24T01:27:34.339893Z","steps":["trace[1505404139] 'process raft request' (duration: 150.476017ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:27:34.340426Z","caller":"traceutil/trace.go:171","msg":"trace[1073480872] transaction","detail":"{read_only:false; response_revision:3107; number_of_response:1; }","duration":"148.472376ms","start":"2026-04-24T01:27:34.191936Z","end":"2026-04-24T01:27:34.340408Z","steps":["trace[1073480872] 'process raft request' (duration: 147.315143ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:28:07.11501Z","caller":"traceutil/trace.go:171","msg":"trace[155807936] transaction","detail":"{read_only:false; response_revision:3287; number_of_response:1; }","duration":"199.20852ms","start":"2026-04-24T01:28:06.915777Z","end":"2026-04-24T01:28:07.114986Z","steps":["trace[155807936] 'process raft request' (duration: 199.047217ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T01:31:55.411688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2841} {"level":"info","ts":"2026-04-24T01:31:55.512323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2841,"took":"99.648927ms","hash":1837952993,"current-db-size-bytes":54538240,"current-db-size":"54 MB","current-db-size-in-use-bytes":30498816,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-24T01:31:55.512391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1837952993,"revision":2841,"compact-revision":-1} {"level":"info","ts":"2026-04-24T01:32:40.9509Z","caller":"traceutil/trace.go:171","msg":"trace[313546340] linearizableReadLoop","detail":"{readStateIndex:6186; appliedIndex:6185; }","duration":"102.938582ms","start":"2026-04-24T01:32:40.847936Z","end":"2026-04-24T01:32:40.950875Z","steps":["trace[313546340] 'read index received' (duration: 102.697226ms)","trace[313546340] 'applied index is now lower than readState.Index' (duration: 240.356µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T01:32:40.950998Z","caller":"traceutil/trace.go:171","msg":"trace[1498485984] transaction","detail":"{read_only:false; response_revision:5967; number_of_response:1; }","duration":"118.783488ms","start":"2026-04-24T01:32:40.832181Z","end":"2026-04-24T01:32:40.950964Z","steps":["trace[1498485984] 'process raft request' (duration: 118.536712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T01:32:40.951388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.129337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T01:32:40.951488Z","caller":"traceutil/trace.go:171","msg":"trace[156602059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5967; }","duration":"103.560708ms","start":"2026-04-24T01:32:40.847912Z","end":"2026-04-24T01:32:40.951472Z","steps":["trace[156602059] 'agreement among raft nodes before linearized reading' (duration: 103.098736ms)"],"step_count":1}