{"level":"warn","ts":"2026-04-17T18:43:20.88578Z","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-17T18:43:20.885868Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.122: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.122:2380","--initial-cluster=instance=https://199.19.213.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.122: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-17T18:43:20.885939Z","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-17T18:43:20.885952Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.122:2380"]} {"level":"info","ts":"2026-04-17T18:43:20.885971Z","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-17T18:43:20.886728Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"]} {"level":"info","ts":"2026-04-17T18:43:20.886826Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.122:2380"],"listen-peer-urls":["https://199.19.213.122:2380"],"advertise-client-urls":["https://199.19.213.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.122: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-17T18:43:20.890963Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.860733ms"} {"level":"info","ts":"2026-04-17T18:43:20.899604Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f9ba8c11acfbc749","cluster-id":"ca7c3e103d4cd533"} {"level":"info","ts":"2026-04-17T18:43:20.899703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T18:43:20.899724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became follower at term 0"} {"level":"info","ts":"2026-04-17T18:43:20.899734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f9ba8c11acfbc749 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T18:43:20.899748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became follower at term 1"} {"level":"info","ts":"2026-04-17T18:43:20.899783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=(17994849268656752457)"} {"level":"warn","ts":"2026-04-17T18:43:20.905254Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T18:43:20.90802Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T18:43:20.909533Z","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-17T18:43:20.913246Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f9ba8c11acfbc749","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T18:43:20.913467Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f9ba8c11acfbc749","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T18:43:20.9135Z","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-17T18:43:20.913617Z","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-17T18:43:20.913634Z","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-17T18:43:20.914199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=(17994849268656752457)"} {"level":"info","ts":"2026-04-17T18:43:20.914367Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ca7c3e103d4cd533","local-member-id":"f9ba8c11acfbc749","added-peer-id":"f9ba8c11acfbc749","added-peer-peer-urls":["https://199.19.213.122:2380"]} {"level":"info","ts":"2026-04-17T18:43:20.915977Z","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-17T18:43:20.916177Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f9ba8c11acfbc749","initial-advertise-peer-urls":["https://199.19.213.122:2380"],"listen-peer-urls":["https://199.19.213.122:2380"],"advertise-client-urls":["https://199.19.213.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T18:43:20.916222Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T18:43:20.916358Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.122:2380"} {"level":"info","ts":"2026-04-17T18:43:20.916374Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.122:2380"} {"level":"info","ts":"2026-04-17T18:43:21.300013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T18:43:21.300128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T18:43:21.300157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 received MsgPreVoteResp from f9ba8c11acfbc749 at term 1"} {"level":"info","ts":"2026-04-17T18:43:21.300183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became candidate at term 2"} {"level":"info","ts":"2026-04-17T18:43:21.3002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 received MsgVoteResp from f9ba8c11acfbc749 at term 2"} {"level":"info","ts":"2026-04-17T18:43:21.300218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became leader at term 2"} {"level":"info","ts":"2026-04-17T18:43:21.300233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f9ba8c11acfbc749 elected leader f9ba8c11acfbc749 at term 2"} {"level":"info","ts":"2026-04-17T18:43:21.30542Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f9ba8c11acfbc749","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.122:2379]}","request-path":"/0/members/f9ba8c11acfbc749/attributes","cluster-id":"ca7c3e103d4cd533","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T18:43:21.305446Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:43:21.305684Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:43:21.305772Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T18:43:21.305839Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T18:43:21.305894Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T18:43:21.307701Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.122:2379"} {"level":"info","ts":"2026-04-17T18:43:21.308172Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T18:43:21.311904Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ca7c3e103d4cd533","local-member-id":"f9ba8c11acfbc749","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:43:21.312056Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:43:21.312126Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T18:43:24.178443Z","caller":"traceutil/trace.go:171","msg":"trace[133803689] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"206.372137ms","start":"2026-04-17T18:43:23.972036Z","end":"2026-04-17T18:43:24.178409Z","steps":["trace[133803689] 'process raft request' (duration: 180.448581ms)","trace[133803689] 'compare' (duration: 25.785182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:43:31.703075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.045308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:43:31.703203Z","caller":"traceutil/trace.go:171","msg":"trace[668608236] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:336; }","duration":"255.815779ms","start":"2026-04-17T18:43:31.447367Z","end":"2026-04-17T18:43:31.703183Z","steps":["trace[668608236] 'read index received' (duration: 73.120662ms)","trace[668608236] 'applied index is now lower than readState.Index' (duration: 182.693907ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:43:31.703276Z","caller":"traceutil/trace.go:171","msg":"trace[1485853586] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"284.64618ms","start":"2026-04-17T18:43:31.418584Z","end":"2026-04-17T18:43:31.70323Z","steps":["trace[1485853586] 'process raft request' (duration: 101.952262ms)","trace[1485853586] 'compare' (duration: 181.903744ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:43:31.703476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.388572ms","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-17T18:43:31.703361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.958063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-17T18:43:31.70351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.840854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:43:31.703541Z","caller":"traceutil/trace.go:171","msg":"trace[1513814909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:326; }","duration":"106.511825ms","start":"2026-04-17T18:43:31.597013Z","end":"2026-04-17T18:43:31.703525Z","steps":["trace[1513814909] 'agreement among raft nodes before linearized reading' (duration: 106.387692ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:31.703564Z","caller":"traceutil/trace.go:171","msg":"trace[1298295555] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:326; }","duration":"256.222081ms","start":"2026-04-17T18:43:31.447315Z","end":"2026-04-17T18:43:31.703537Z","steps":["trace[1298295555] 'agreement among raft nodes before linearized reading' (duration: 255.953294ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:31.70359Z","caller":"traceutil/trace.go:171","msg":"trace[1002094118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:326; }","duration":"155.942237ms","start":"2026-04-17T18:43:31.547613Z","end":"2026-04-17T18:43:31.703555Z","steps":["trace[1002094118] 'agreement among raft nodes before linearized reading' (duration: 155.757882ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:32.052048Z","caller":"traceutil/trace.go:171","msg":"trace[1220511147] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:337; }","duration":"344.767142ms","start":"2026-04-17T18:43:31.707253Z","end":"2026-04-17T18:43:32.052021Z","steps":["trace[1220511147] 'read index received' (duration: 50.06155ms)","trace[1220511147] 'applied index is now lower than readState.Index' (duration: 294.702262ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:43:32.052214Z","caller":"traceutil/trace.go:171","msg":"trace[1406664471] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"339.631292ms","start":"2026-04-17T18:43:31.712565Z","end":"2026-04-17T18:43:32.052197Z","steps":["trace[1406664471] 'process raft request' (duration: 339.393545ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:32.052248Z","caller":"traceutil/trace.go:171","msg":"trace[1406566806] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"345.049721ms","start":"2026-04-17T18:43:31.707157Z","end":"2026-04-17T18:43:32.052207Z","steps":["trace[1406566806] 'process raft request' (duration: 327.54797ms)","trace[1406566806] 'compare' (duration: 16.615735ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:43:32.052228Z","caller":"traceutil/trace.go:171","msg":"trace[1691598061] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"339.831588ms","start":"2026-04-17T18:43:31.712362Z","end":"2026-04-17T18:43:32.052194Z","steps":["trace[1691598061] 'process raft request' (duration: 339.53088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:32.052366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.770236ms","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-17T18:43:32.052373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.088331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-17T18:43:32.052124Z","caller":"traceutil/trace.go:171","msg":"trace[1016636165] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"344.630078ms","start":"2026-04-17T18:43:31.707461Z","end":"2026-04-17T18:43:32.052091Z","steps":["trace[1016636165] 'process raft request' (duration: 344.3435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:32.052374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:43:31.707136Z","time spent":"345.191704ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T18:43:32.052461Z","caller":"traceutil/trace.go:171","msg":"trace[993768734] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:331; }","duration":"345.209804ms","start":"2026-04-17T18:43:31.707231Z","end":"2026-04-17T18:43:32.052441Z","steps":["trace[993768734] 'agreement among raft nodes before linearized reading' (duration: 345.002188ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:32.052488Z","caller":"traceutil/trace.go:171","msg":"trace[1827563292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:331; }","duration":"339.883359ms","start":"2026-04-17T18:43:31.712548Z","end":"2026-04-17T18:43:32.052431Z","steps":["trace[1827563292] 'agreement among raft nodes before linearized reading' (duration: 339.693504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:32.052523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:43:31.712537Z","time spent":"339.980133ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:43:32.052518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:43:31.707222Z","time spent":"345.287337ms","remote":"127.0.0.1:55782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-04-17T18:43:32.052626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:43:31.712539Z","time spent":"339.735615ms","remote":"127.0.0.1:56048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:43:32.052706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:43:31.712353Z","time spent":"339.988282ms","remote":"127.0.0.1:55774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:43:32.052839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:43:31.707369Z","time spent":"345.091201ms","remote":"127.0.0.1:55774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:43:56.879008Z","caller":"traceutil/trace.go:171","msg":"trace[831883498] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"117.360003ms","start":"2026-04-17T18:43:56.761623Z","end":"2026-04-17T18:43:56.878983Z","steps":["trace[831883498] 'process raft request' (duration: 117.207359ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:57.05685Z","caller":"traceutil/trace.go:171","msg":"trace[146905495] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"226.354505ms","start":"2026-04-17T18:43:56.830464Z","end":"2026-04-17T18:43:57.056819Z","steps":["trace[146905495] 'process raft request' (duration: 206.228515ms)","trace[146905495] 'compare' (duration: 18.945936ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:43:57.335317Z","caller":"traceutil/trace.go:171","msg":"trace[635343990] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"212.759263ms","start":"2026-04-17T18:43:57.122514Z","end":"2026-04-17T18:43:57.335273Z","steps":["trace[635343990] 'process raft request' (duration: 164.055339ms)","trace[635343990] 'compare' (duration: 48.256031ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:43:57.510464Z","caller":"traceutil/trace.go:171","msg":"trace[7496382] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"159.321843ms","start":"2026-04-17T18:43:57.35104Z","end":"2026-04-17T18:43:57.510362Z","steps":["trace[7496382] 'process raft request' (duration: 158.52233ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:43:58.069803Z","caller":"traceutil/trace.go:171","msg":"trace[619439293] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"205.790423ms","start":"2026-04-17T18:43:57.863979Z","end":"2026-04-17T18:43:58.06977Z","steps":["trace[619439293] 'read index received' (duration: 204.582987ms)","trace[619439293] 'applied index is now lower than readState.Index' (duration: 1.206346ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:43:58.069916Z","caller":"traceutil/trace.go:171","msg":"trace[1377902701] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"317.218365ms","start":"2026-04-17T18:43:57.752672Z","end":"2026-04-17T18:43:58.069891Z","steps":["trace[1377902701] 'process raft request' (duration: 316.35826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:58.070044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.04362ms","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-17T18:43:58.070089Z","caller":"traceutil/trace.go:171","msg":"trace[969635883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"206.183974ms","start":"2026-04-17T18:43:57.863892Z","end":"2026-04-17T18:43:58.070076Z","steps":["trace[969635883] 'agreement among raft nodes before linearized reading' (duration: 206.005059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:43:58.070181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:43:57.752639Z","time spent":"317.39406ms","remote":"127.0.0.1:55688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:44:13.49087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.685687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-m2hzm\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-17T18:44:13.491012Z","caller":"traceutil/trace.go:171","msg":"trace[2042830498] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-m2hzm; range_end:; response_count:1; response_revision:735; }","duration":"155.880254ms","start":"2026-04-17T18:44:13.335114Z","end":"2026-04-17T18:44:13.490994Z","steps":["trace[2042830498] 'range keys from in-memory index tree' (duration: 155.501282ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:44:55.688474Z","caller":"traceutil/trace.go:171","msg":"trace[1610945249] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"143.59733ms","start":"2026-04-17T18:44:55.544847Z","end":"2026-04-17T18:44:55.688445Z","steps":["trace[1610945249] 'process raft request' (duration: 113.316467ms)","trace[1610945249] 'compare' (duration: 30.16774ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:45:27.762277Z","caller":"traceutil/trace.go:171","msg":"trace[1605918742] linearizableReadLoop","detail":"{readStateIndex:1869; appliedIndex:1868; }","duration":"213.929817ms","start":"2026-04-17T18:45:27.548323Z","end":"2026-04-17T18:45:27.762253Z","steps":["trace[1605918742] 'read index received' (duration: 213.68874ms)","trace[1605918742] 'applied index is now lower than readState.Index' (duration: 239.977µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:45:27.762385Z","caller":"traceutil/trace.go:171","msg":"trace[1053790148] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"271.28826ms","start":"2026-04-17T18:45:27.49104Z","end":"2026-04-17T18:45:27.762329Z","steps":["trace[1053790148] 'process raft request' (duration: 271.047103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:27.762436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.091721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:45:27.762519Z","caller":"traceutil/trace.go:171","msg":"trace[2030620941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1801; }","duration":"214.224745ms","start":"2026-04-17T18:45:27.548276Z","end":"2026-04-17T18:45:27.762501Z","steps":["trace[2030620941] 'agreement among raft nodes before linearized reading' (duration: 214.085651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:45:27.762532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.965483ms","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-17T18:45:27.762772Z","caller":"traceutil/trace.go:171","msg":"trace[1754264558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1801; }","duration":"183.18272ms","start":"2026-04-17T18:45:27.579511Z","end":"2026-04-17T18:45:27.762694Z","steps":["trace[1754264558] 'agreement among raft nodes before linearized reading' (duration: 182.894851ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:28.162465Z","caller":"traceutil/trace.go:171","msg":"trace[1487347975] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"136.652759ms","start":"2026-04-17T18:45:28.025784Z","end":"2026-04-17T18:45:28.162437Z","steps":["trace[1487347975] 'process raft request' (duration: 136.468334ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:45:40.708674Z","caller":"traceutil/trace.go:171","msg":"trace[1951093284] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"103.642788ms","start":"2026-04-17T18:45:40.605008Z","end":"2026-04-17T18:45:40.708651Z","steps":["trace[1951093284] 'process raft request' (duration: 103.147624ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:46:17.295752Z","caller":"traceutil/trace.go:171","msg":"trace[46908035] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"271.246414ms","start":"2026-04-17T18:46:17.024469Z","end":"2026-04-17T18:46:17.295715Z","steps":["trace[46908035] 'process raft request' (duration: 201.982206ms)","trace[46908035] 'compare' (duration: 68.975308ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:46:17.55215Z","caller":"traceutil/trace.go:171","msg":"trace[2032740062] linearizableReadLoop","detail":"{readStateIndex:2199; appliedIndex:2197; }","duration":"503.577575ms","start":"2026-04-17T18:46:17.048546Z","end":"2026-04-17T18:46:17.552123Z","steps":["trace[2032740062] 'read index received' (duration: 177.933789ms)","trace[2032740062] 'applied index is now lower than readState.Index' (duration: 325.642536ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:46:17.55223Z","caller":"traceutil/trace.go:171","msg":"trace[614932265] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"527.000734ms","start":"2026-04-17T18:46:17.025201Z","end":"2026-04-17T18:46:17.552202Z","steps":["trace[614932265] 'process raft request' (duration: 510.944475ms)","trace[614932265] 'compare' (duration: 15.634187ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:46:17.552238Z","caller":"traceutil/trace.go:171","msg":"trace[347509396] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"469.327618ms","start":"2026-04-17T18:46:17.082875Z","end":"2026-04-17T18:46:17.552203Z","steps":["trace[347509396] 'process raft request' (duration: 469.147842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:17.552338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.788682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:46:17.552393Z","caller":"traceutil/trace.go:171","msg":"trace[356239214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2122; }","duration":"503.886454ms","start":"2026-04-17T18:46:17.048489Z","end":"2026-04-17T18:46:17.552376Z","steps":["trace[356239214] 'agreement among raft nodes before linearized reading' (duration: 503.800192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:17.552391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:17.025182Z","time spent":"527.117427ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:46:17.552439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:17.048459Z","time spent":"503.969947ms","remote":"127.0.0.1:55570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:46:17.552593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.658676ms","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-17T18:46:17.552706Z","caller":"traceutil/trace.go:171","msg":"trace[325544863] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2122; }","duration":"441.822172ms","start":"2026-04-17T18:46:17.110853Z","end":"2026-04-17T18:46:17.552676Z","steps":["trace[325544863] 'agreement among raft nodes before linearized reading' (duration: 441.501103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:46:17.552757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:17.11083Z","time spent":"441.917204ms","remote":"127.0.0.1:58776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-17T18:46:17.553123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:46:17.082846Z","time spent":"469.554093ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:47:31.505691Z","caller":"traceutil/trace.go:171","msg":"trace[2089172321] transaction","detail":"{read_only:false; response_revision:2719; number_of_response:1; }","duration":"166.482461ms","start":"2026-04-17T18:47:31.339183Z","end":"2026-04-17T18:47:31.505665Z","steps":["trace[2089172321] 'process raft request' (duration: 166.419379ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:47:31.505752Z","caller":"traceutil/trace.go:171","msg":"trace[2038743546] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"216.978115ms","start":"2026-04-17T18:47:31.288738Z","end":"2026-04-17T18:47:31.505716Z","steps":["trace[2038743546] 'process raft request' (duration: 185.31818ms)","trace[2038743546] 'compare' (duration: 31.397078ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:49:46.539266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182183429464854,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:49:47.040026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182183429464854,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:49:47.541102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182183429464854,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:49:48.04218Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182183429464854,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:49:48.542845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182183429464854,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:49:48.594352Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.893587991s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T18:49:48.594755Z","caller":"traceutil/trace.go:171","msg":"trace[1725716002] transaction","detail":"{read_only:false; response_revision:3628; number_of_response:1; }","duration":"2.894111856s","start":"2026-04-17T18:49:45.700618Z","end":"2026-04-17T18:49:48.594729Z","steps":["trace[1725716002] 'process raft request' (duration: 2.893927721s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.594858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:45.700588Z","time spent":"2.894206389s","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:49:48.595272Z","caller":"traceutil/trace.go:171","msg":"trace[1569714975] transaction","detail":"{read_only:false; response_revision:3629; number_of_response:1; }","duration":"2.61973295s","start":"2026-04-17T18:49:45.975521Z","end":"2026-04-17T18:49:48.595254Z","steps":["trace[1569714975] 'process raft request' (duration: 2.619611526s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.595416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:45.975479Z","time spent":"2.619878094s","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:49:48.59543Z","caller":"traceutil/trace.go:171","msg":"trace[976337490] transaction","detail":"{read_only:false; response_revision:3630; number_of_response:1; }","duration":"2.267284009s","start":"2026-04-17T18:49:46.328135Z","end":"2026-04-17T18:49:48.595419Z","steps":["trace[976337490] 'process raft request' (duration: 2.267092104s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.595531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.328105Z","time spent":"2.267391012s","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:49:48.595623Z","caller":"traceutil/trace.go:171","msg":"trace[2125055609] linearizableReadLoop","detail":"{readStateIndex:3767; appliedIndex:3763; }","duration":"2.557182978s","start":"2026-04-17T18:49:46.038429Z","end":"2026-04-17T18:49:48.595612Z","steps":["trace[2125055609] 'read index received' (duration: 2.556129358s)","trace[2125055609] 'applied index is now lower than readState.Index' (duration: 1.05236ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:49:48.595677Z","caller":"traceutil/trace.go:171","msg":"trace[38978362] transaction","detail":"{read_only:false; response_revision:3631; number_of_response:1; }","duration":"1.799115922s","start":"2026-04-17T18:49:46.796554Z","end":"2026-04-17T18:49:48.59567Z","steps":["trace[38978362] 'process raft request' (duration: 1.798816294s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.595722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.796531Z","time spent":"1.799163414s","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:49:48.595863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.453186005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-17T18:49:48.59589Z","caller":"traceutil/trace.go:171","msg":"trace[754998143] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3631; }","duration":"2.453253276s","start":"2026-04-17T18:49:46.142629Z","end":"2026-04-17T18:49:48.595882Z","steps":["trace[754998143] 'agreement among raft nodes before linearized reading' (duration: 2.453160334s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.595889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.557463695s","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-17T18:49:48.595952Z","caller":"traceutil/trace.go:171","msg":"trace[25453232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3631; }","duration":"2.557549298s","start":"2026-04-17T18:49:46.03838Z","end":"2026-04-17T18:49:48.595929Z","steps":["trace[25453232] 'agreement among raft nodes before linearized reading' (duration: 2.557392873s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.595983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.143819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-17T18:49:48.595977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.038363Z","time spent":"2.55760748s","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T18:49:48.596009Z","caller":"traceutil/trace.go:171","msg":"trace[1507408404] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3631; }","duration":"592.214682ms","start":"2026-04-17T18:49:48.003784Z","end":"2026-04-17T18:49:48.595999Z","steps":["trace[1507408404] 'agreement among raft nodes before linearized reading' (duration: 592.151609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.596032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:48.003772Z","time spent":"592.253752ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-17T18:49:48.595914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.142607Z","time spent":"2.453300978s","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T18:49:48.596109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547084894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:49:48.596131Z","caller":"traceutil/trace.go:171","msg":"trace[1842626671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3631; }","duration":"1.547154986s","start":"2026-04-17T18:49:47.048969Z","end":"2026-04-17T18:49:48.596124Z","steps":["trace[1842626671] 'agreement among raft nodes before linearized reading' (duration: 1.547119905s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.596153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.048953Z","time spent":"1.547194117s","remote":"127.0.0.1:55570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:49:48.596153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530694496s","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-17T18:49:48.596207Z","caller":"traceutil/trace.go:171","msg":"trace[118464205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3631; }","duration":"1.530794049s","start":"2026-04-17T18:49:47.0654Z","end":"2026-04-17T18:49:48.596194Z","steps":["trace[118464205] 'agreement among raft nodes before linearized reading' (duration: 1.530632925s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.596172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.557733614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nz9zn\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-17T18:49:48.596244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.065386Z","time spent":"1.530851571s","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T18:49:48.596271Z","caller":"traceutil/trace.go:171","msg":"trace[969353969] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nz9zn; range_end:; response_count:1; response_revision:3631; }","duration":"2.557866427s","start":"2026-04-17T18:49:46.038374Z","end":"2026-04-17T18:49:48.596241Z","steps":["trace[969353969] 'agreement among raft nodes before linearized reading' (duration: 2.557480646s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.596316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.238971824s","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":"warn","ts":"2026-04-17T18:49:48.596329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.038357Z","time spent":"2.557963581s","remote":"127.0.0.1:55774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-nz9zn\" "} {"level":"warn","ts":"2026-04-17T18:49:48.596367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.750799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T18:49:48.596366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.314334671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T18:49:48.596391Z","caller":"traceutil/trace.go:171","msg":"trace[1008678566] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:3631; }","duration":"604.803209ms","start":"2026-04-17T18:49:47.99158Z","end":"2026-04-17T18:49:48.596383Z","steps":["trace[1008678566] 'agreement among raft nodes before linearized reading' (duration: 604.738888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.596395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.944795104s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:49:48.596399Z","caller":"traceutil/trace.go:171","msg":"trace[864087269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3631; }","duration":"2.314406093s","start":"2026-04-17T18:49:46.281984Z","end":"2026-04-17T18:49:48.59639Z","steps":["trace[864087269] 'agreement among raft nodes before linearized reading' (duration: 2.31432197s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.596419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.991566Z","time spent":"604.841351ms","remote":"127.0.0.1:55774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":29,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-04-17T18:49:48.596424Z","caller":"traceutil/trace.go:171","msg":"trace[504670276] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:3631; }","duration":"1.944845544s","start":"2026-04-17T18:49:46.65157Z","end":"2026-04-17T18:49:48.596415Z","steps":["trace[504670276] 'agreement among raft nodes before linearized reading' (duration: 1.944810993s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.596434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.281965Z","time spent":"2.314462895s","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:49:48.596446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.651557Z","time spent":"1.944883646s","remote":"127.0.0.1:45704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:49:48.596465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047862371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:49:48.596501Z","caller":"traceutil/trace.go:171","msg":"trace[708852200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3631; }","duration":"1.047923013s","start":"2026-04-17T18:49:47.548564Z","end":"2026-04-17T18:49:48.596487Z","steps":["trace[708852200] 'agreement among raft nodes before linearized reading' (duration: 1.047823991s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.596516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.548547Z","time spent":"1.047966174s","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:49:48.596559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.565598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-04-17T18:49:48.596591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415286348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-17T18:49:48.596366Z","caller":"traceutil/trace.go:171","msg":"trace[1894724549] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:3631; }","duration":"2.239053566s","start":"2026-04-17T18:49:46.357296Z","end":"2026-04-17T18:49:48.59635Z","steps":["trace[1894724549] 'agreement among raft nodes before linearized reading' (duration: 2.238969194s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.596614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.029554278s","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-17T18:49:48.596627Z","caller":"traceutil/trace.go:171","msg":"trace[1053587231] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3631; }","duration":"991.636929ms","start":"2026-04-17T18:49:47.604958Z","end":"2026-04-17T18:49:48.596595Z","steps":["trace[1053587231] 'agreement among raft nodes before linearized reading' (duration: 991.416783ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:49:48.596637Z","caller":"traceutil/trace.go:171","msg":"trace[490240923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3631; }","duration":"2.029613019s","start":"2026-04-17T18:49:46.567016Z","end":"2026-04-17T18:49:48.596629Z","steps":["trace[490240923] 'agreement among raft nodes before linearized reading' (duration: 2.029528547s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.596642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.357283Z","time spent":"2.239345234s","remote":"127.0.0.1:45516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:49:48.596659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.566997Z","time spent":"2.029658591s","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T18:49:48.596661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.604948Z","time spent":"991.704921ms","remote":"127.0.0.1:55774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-17T18:49:48.596637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.405310212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T18:49:48.596726Z","caller":"traceutil/trace.go:171","msg":"trace[855686153] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:3631; }","duration":"2.405475076s","start":"2026-04-17T18:49:46.191225Z","end":"2026-04-17T18:49:48.5967Z","steps":["trace[855686153] 'agreement among raft nodes before linearized reading' (duration: 2.405284421s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.596752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.14363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-grafana-client-secret\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-04-17T18:49:48.596632Z","caller":"traceutil/trace.go:171","msg":"trace[642270495] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:3631; }","duration":"1.415359251s","start":"2026-04-17T18:49:47.181256Z","end":"2026-04-17T18:49:48.596616Z","steps":["trace[642270495] 'agreement among raft nodes before linearized reading' (duration: 1.415264238s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:49:48.596775Z","caller":"traceutil/trace.go:171","msg":"trace[192543199] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-grafana-client-secret; range_end:; response_count:1; response_revision:3631; }","duration":"605.213501ms","start":"2026-04-17T18:49:47.991553Z","end":"2026-04-17T18:49:48.596767Z","steps":["trace[192543199] 'agreement among raft nodes before linearized reading' (duration: 605.147089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:49:48.596774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:46.191203Z","time spent":"2.405559629s","remote":"127.0.0.1:53016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:49:48.596799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.99154Z","time spent":"605.254572ms","remote":"127.0.0.1:55992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":1243,"request content":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-grafana-client-secret\" "} {"level":"warn","ts":"2026-04-17T18:49:48.596857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:49:47.181237Z","time spent":"1.415573887s","remote":"127.0.0.1:55754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1657,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-04-17T18:50:22.826222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182183429465406,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:50:23.3272Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182183429465406,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:50:23.495794Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.209026573s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T18:50:23.496324Z","caller":"traceutil/trace.go:171","msg":"trace[1866739372] transaction","detail":"{read_only:false; response_revision:3818; number_of_response:1; }","duration":"1.209677921s","start":"2026-04-17T18:50:22.286613Z","end":"2026-04-17T18:50:23.496291Z","steps":["trace[1866739372] 'process raft request' (duration: 1.209454984s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.496612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.286591Z","time spent":"1.209923498s","remote":"127.0.0.1:55774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17039,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:50:23.500936Z","caller":"traceutil/trace.go:171","msg":"trace[1811981792] transaction","detail":"{read_only:false; response_revision:3819; number_of_response:1; }","duration":"1.162528938s","start":"2026-04-17T18:50:22.338387Z","end":"2026-04-17T18:50:23.500916Z","steps":["trace[1811981792] 'process raft request' (duration: 1.162377464s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.501054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.338367Z","time spent":"1.16262767s","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:50:23.501233Z","caller":"traceutil/trace.go:171","msg":"trace[1396404245] transaction","detail":"{read_only:false; response_revision:3820; number_of_response:1; }","duration":"795.956135ms","start":"2026-04-17T18:50:22.705258Z","end":"2026-04-17T18:50:23.501214Z","steps":["trace[1396404245] 'process raft request' (duration: 795.620756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.501325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.705236Z","time spent":"796.046397ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:50:23.501501Z","caller":"traceutil/trace.go:171","msg":"trace[1294764360] transaction","detail":"{read_only:false; response_revision:3821; number_of_response:1; }","duration":"792.599889ms","start":"2026-04-17T18:50:22.708869Z","end":"2026-04-17T18:50:23.501469Z","steps":["trace[1294764360] 'process raft request' (duration: 792.29237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.501709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.708852Z","time spent":"792.779755ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:50:23.501923Z","caller":"traceutil/trace.go:171","msg":"trace[710178368] transaction","detail":"{read_only:false; response_revision:3822; number_of_response:1; }","duration":"788.757061ms","start":"2026-04-17T18:50:22.713147Z","end":"2026-04-17T18:50:23.501904Z","steps":["trace[710178368] 'process raft request' (duration: 788.252116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.502015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.71313Z","time spent":"788.839742ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:50:23.502118Z","caller":"traceutil/trace.go:171","msg":"trace[853745059] linearizableReadLoop","detail":"{readStateIndex:3966; appliedIndex:3961; }","duration":"1.175875688s","start":"2026-04-17T18:50:22.326159Z","end":"2026-04-17T18:50:23.502035Z","steps":["trace[853745059] 'read index received' (duration: 1.169900358s)","trace[853745059] 'applied index is now lower than readState.Index' (duration: 5.97091ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:50:23.502275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176089664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-17T18:50:23.502332Z","caller":"traceutil/trace.go:171","msg":"trace[342839949] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:3822; }","duration":"1.176184827s","start":"2026-04-17T18:50:22.326128Z","end":"2026-04-17T18:50:23.502313Z","steps":["trace[342839949] 'agreement among raft nodes before linearized reading' (duration: 1.175997432s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.502377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.326115Z","time spent":"1.176253119s","remote":"127.0.0.1:55754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1657,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-04-17T18:50:23.502388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017578928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-prometheus-oauth2-proxy\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:50:23.502429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.30856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:50:23.502444Z","caller":"traceutil/trace.go:171","msg":"trace[64205600] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-prometheus-oauth2-proxy; range_end:; response_count:0; response_revision:3822; }","duration":"1.01765775s","start":"2026-04-17T18:50:22.48477Z","end":"2026-04-17T18:50:23.502428Z","steps":["trace[64205600] 'agreement among raft nodes before linearized reading' (duration: 1.017576688s)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:50:23.502471Z","caller":"traceutil/trace.go:171","msg":"trace[1819477031] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:3822; }","duration":"303.388572ms","start":"2026-04-17T18:50:23.199067Z","end":"2026-04-17T18:50:23.502456Z","steps":["trace[1819477031] 'agreement among raft nodes before linearized reading' (duration: 303.31785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.502512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:23.199051Z","time spent":"303.451534ms","remote":"127.0.0.1:45554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-17T18:50:23.502586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.077722ms","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-17T18:50:23.502622Z","caller":"traceutil/trace.go:171","msg":"trace[864982819] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3822; }","duration":"734.137554ms","start":"2026-04-17T18:50:22.768473Z","end":"2026-04-17T18:50:23.502611Z","steps":["trace[864982819] 'agreement among raft nodes before linearized reading' (duration: 734.02189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.502646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.768456Z","time spent":"734.184245ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-17T18:50:23.502679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.64849ms","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-17T18:50:23.502767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.596583ms","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-17T18:50:23.502792Z","caller":"traceutil/trace.go:171","msg":"trace[999542993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3822; }","duration":"743.644154ms","start":"2026-04-17T18:50:22.759141Z","end":"2026-04-17T18:50:23.502785Z","steps":["trace[999542993] 'agreement among raft nodes before linearized reading' (duration: 743.574062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.502823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.75913Z","time spent":"743.685835ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T18:50:23.502787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.026636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-17T18:50:23.502828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.412299ms","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-17T18:50:23.502837Z","caller":"traceutil/trace.go:171","msg":"trace[425256923] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3822; }","duration":"733.797864ms","start":"2026-04-17T18:50:22.768973Z","end":"2026-04-17T18:50:23.502771Z","steps":["trace[425256923] 'agreement among raft nodes before linearized reading' (duration: 733.379362ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:50:23.502865Z","caller":"traceutil/trace.go:171","msg":"trace[2122530772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3822; }","duration":"737.133549ms","start":"2026-04-17T18:50:22.765717Z","end":"2026-04-17T18:50:23.50285Z","steps":["trace[2122530772] 'agreement among raft nodes before linearized reading' (duration: 736.963644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.502901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.867532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:50:23.502912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.768961Z","time spent":"733.937809ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T18:50:23.502916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.765704Z","time spent":"737.203381ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T18:50:23.502926Z","caller":"traceutil/trace.go:171","msg":"trace[1938211354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3822; }","duration":"954.913213ms","start":"2026-04-17T18:50:22.548006Z","end":"2026-04-17T18:50:23.502919Z","steps":["trace[1938211354] 'agreement among raft nodes before linearized reading' (duration: 954.871292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.502955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.547995Z","time spent":"954.955414ms","remote":"127.0.0.1:55570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T18:50:23.502478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.484757Z","time spent":"1.017714052s","remote":"127.0.0.1:56032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/monitoring/kube-prometheus-stack-prometheus-oauth2-proxy\" "} {"level":"warn","ts":"2026-04-17T18:50:23.503045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.188491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nz9zn\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-04-17T18:50:23.503052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.615611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T18:50:23.503098Z","caller":"traceutil/trace.go:171","msg":"trace[1974617149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3822; }","duration":"659.683322ms","start":"2026-04-17T18:50:22.843404Z","end":"2026-04-17T18:50:23.503087Z","steps":["trace[1974617149] 'agreement among raft nodes before linearized reading' (duration: 659.578149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.503132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.843386Z","time spent":"659.739514ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T18:50:23.502863Z","caller":"traceutil/trace.go:171","msg":"trace[608445754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3822; }","duration":"735.552324ms","start":"2026-04-17T18:50:22.767302Z","end":"2026-04-17T18:50:23.502854Z","steps":["trace[608445754] 'agreement among raft nodes before linearized reading' (duration: 735.461572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.503201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.767265Z","time spent":"735.926555ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T18:50:23.503076Z","caller":"traceutil/trace.go:171","msg":"trace[931554741] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nz9zn; range_end:; response_count:1; response_revision:3822; }","duration":"658.255822ms","start":"2026-04-17T18:50:22.84481Z","end":"2026-04-17T18:50:23.503066Z","steps":["trace[931554741] 'agreement among raft nodes before linearized reading' (duration: 658.17096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:50:23.503847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:22.844788Z","time spent":"658.941211ms","remote":"127.0.0.1:55774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-nz9zn\" "} {"level":"warn","ts":"2026-04-17T18:51:00.166912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182183429466091,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T18:51:00.172193Z","caller":"traceutil/trace.go:171","msg":"trace[2799057] transaction","detail":"{read_only:false; response_revision:4094; number_of_response:1; }","duration":"506.526488ms","start":"2026-04-17T18:50:59.665633Z","end":"2026-04-17T18:51:00.17216Z","steps":["trace[2799057] 'process raft request' (duration: 503.42216ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:00.172314Z","caller":"traceutil/trace.go:171","msg":"trace[1989565409] transaction","detail":"{read_only:false; response_revision:4095; number_of_response:1; }","duration":"504.567543ms","start":"2026-04-17T18:50:59.667612Z","end":"2026-04-17T18:51:00.17218Z","steps":["trace[1989565409] 'process raft request' (duration: 504.082009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.17252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.667589Z","time spent":"504.833279ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:51:00.172582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.665623Z","time spent":"506.636972ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:51:00.173981Z","caller":"traceutil/trace.go:171","msg":"trace[759707330] linearizableReadLoop","detail":"{readStateIndex:4260; appliedIndex:4257; }","duration":"507.887026ms","start":"2026-04-17T18:50:59.666064Z","end":"2026-04-17T18:51:00.173951Z","steps":["trace[759707330] 'read index received' (duration: 502.964877ms)","trace[759707330] 'applied index is now lower than readState.Index' (duration: 4.920749ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:51:00.174365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.292699ms","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-17T18:51:00.17451Z","caller":"traceutil/trace.go:171","msg":"trace[979250610] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4095; }","duration":"508.473944ms","start":"2026-04-17T18:50:59.666011Z","end":"2026-04-17T18:51:00.174485Z","steps":["trace[979250610] 'agreement among raft nodes before linearized reading' (duration: 508.133384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.174527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.094416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-17T18:51:00.174574Z","caller":"traceutil/trace.go:171","msg":"trace[39941362] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4095; }","duration":"213.187208ms","start":"2026-04-17T18:50:59.961373Z","end":"2026-04-17T18:51:00.174561Z","steps":["trace[39941362] 'agreement among raft nodes before linearized reading' (duration: 213.033824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.174591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.665994Z","time spent":"508.582497ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-17T18:51:00.174799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.877605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-17T18:51:00.174824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.8917ms","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-17T18:51:00.174857Z","caller":"traceutil/trace.go:171","msg":"trace[155098192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4095; }","duration":"482.978488ms","start":"2026-04-17T18:50:59.691864Z","end":"2026-04-17T18:51:00.174843Z","steps":["trace[155098192] 'agreement among raft nodes before linearized reading' (duration: 482.597687ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:00.174862Z","caller":"traceutil/trace.go:171","msg":"trace[2036499187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4095; }","duration":"479.944692ms","start":"2026-04-17T18:50:59.694908Z","end":"2026-04-17T18:51:00.174852Z","steps":["trace[2036499187] 'agreement among raft nodes before linearized reading' (duration: 479.51823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.17489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.694901Z","time spent":"479.981373ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T18:51:00.174893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.691851Z","time spent":"483.034289ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T18:51:00.175056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.227236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nz9zn\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-17T18:51:00.175093Z","caller":"traceutil/trace.go:171","msg":"trace[1347583026] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nz9zn; range_end:; response_count:1; response_revision:4095; }","duration":"476.292038ms","start":"2026-04-17T18:50:59.698792Z","end":"2026-04-17T18:51:00.175084Z","steps":["trace[1347583026] 'agreement among raft nodes before linearized reading' (duration: 475.581918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.175125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.698782Z","time spent":"476.337769ms","remote":"127.0.0.1:55774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-nz9zn\" "} {"level":"warn","ts":"2026-04-17T18:51:00.174611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.32337ms","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-17T18:51:00.175474Z","caller":"traceutil/trace.go:171","msg":"trace[2102453124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4095; }","duration":"489.224446ms","start":"2026-04-17T18:50:59.686222Z","end":"2026-04-17T18:51:00.175446Z","steps":["trace[2102453124] 'agreement among raft nodes before linearized reading' (duration: 488.139316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:00.175796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:50:59.686209Z","time spent":"489.559115ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T18:51:04.865583Z","caller":"traceutil/trace.go:171","msg":"trace[621671] linearizableReadLoop","detail":"{readStateIndex:4285; appliedIndex:4284; }","duration":"118.677288ms","start":"2026-04-17T18:51:04.74688Z","end":"2026-04-17T18:51:04.865557Z","steps":["trace[621671] 'read index received' (duration: 118.550055ms)","trace[621671] 'applied index is now lower than readState.Index' (duration: 125.473µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:51:04.865819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.913904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:51:04.865878Z","caller":"traceutil/trace.go:171","msg":"trace[448643997] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:4119; }","duration":"119.034498ms","start":"2026-04-17T18:51:04.746829Z","end":"2026-04-17T18:51:04.865863Z","steps":["trace[448643997] 'agreement among raft nodes before linearized reading' (duration: 118.914934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.087049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182183429466325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T18:51:09.089032Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.843419035s","expected-duration":"1s"} {"level":"info","ts":"2026-04-17T18:51:09.090467Z","caller":"traceutil/trace.go:171","msg":"trace[1117173999] linearizableReadLoop","detail":"{readStateIndex:4289; appliedIndex:4288; }","duration":"3.831768094s","start":"2026-04-17T18:51:05.258683Z","end":"2026-04-17T18:51:09.090451Z","steps":["trace[1117173999] 'read index received' (duration: 3.831570738s)","trace[1117173999] 'applied index is now lower than readState.Index' (duration: 196.586µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:51:09.090625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.831933788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-17T18:51:09.090642Z","caller":"traceutil/trace.go:171","msg":"trace[101870252] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4122; }","duration":"3.832010991s","start":"2026-04-17T18:51:05.258627Z","end":"2026-04-17T18:51:09.090638Z","steps":["trace[101870252] 'agreement among raft nodes before linearized reading' (duration: 3.831889128s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.090668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:05.258606Z","time spent":"3.832057962s","remote":"127.0.0.1:55774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-17T18:51:09.090795Z","caller":"traceutil/trace.go:171","msg":"trace[2130542577] transaction","detail":"{read_only:false; response_revision:4122; number_of_response:1; }","duration":"3.845916755s","start":"2026-04-17T18:51:05.244873Z","end":"2026-04-17T18:51:09.090789Z","steps":["trace[2130542577] 'process raft request' (duration: 3.84538298s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:09.090842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:51:05.24485Z","time spent":"3.845957597s","remote":"127.0.0.1:55688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":451223,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T18:51:30.323808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.277051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T18:51:30.323845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.647636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e859fba-5ae2-4aff-ae91-b0db30761f52\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-17T18:51:30.323915Z","caller":"traceutil/trace.go:171","msg":"trace[132662959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4896; }","duration":"145.424845ms","start":"2026-04-17T18:51:30.178468Z","end":"2026-04-17T18:51:30.323893Z","steps":["trace[132662959] 'range keys from in-memory index tree' (duration: 145.20467ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:30.323943Z","caller":"traceutil/trace.go:171","msg":"trace[8788745] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e859fba-5ae2-4aff-ae91-b0db30761f52; range_end:; response_count:1; response_revision:4896; }","duration":"182.825391ms","start":"2026-04-17T18:51:30.14109Z","end":"2026-04-17T18:51:30.323916Z","steps":["trace[8788745] 'range keys from in-memory index tree' (duration: 182.44485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:51:30.323952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.659654ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:51:30.3241Z","caller":"traceutil/trace.go:171","msg":"trace[175495150] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4896; }","duration":"117.828619ms","start":"2026-04-17T18:51:30.206249Z","end":"2026-04-17T18:51:30.324077Z","steps":["trace[175495150] 'range keys from in-memory index tree' (duration: 117.637213ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:51:46.62948Z","caller":"traceutil/trace.go:171","msg":"trace[109362513] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"139.092854ms","start":"2026-04-17T18:51:46.490361Z","end":"2026-04-17T18:51:46.629454Z","steps":["trace[109362513] 'process raft request' (duration: 138.91829ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:52:02.812887Z","caller":"traceutil/trace.go:171","msg":"trace[2106336035] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"119.827605ms","start":"2026-04-17T18:52:02.693027Z","end":"2026-04-17T18:52:02.812854Z","steps":["trace[2106336035] 'process raft request' (duration: 112.073225ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:52:02.823689Z","caller":"traceutil/trace.go:171","msg":"trace[864447167] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"130.509039ms","start":"2026-04-17T18:52:02.693154Z","end":"2026-04-17T18:52:02.823663Z","steps":["trace[864447167] 'process raft request' (duration: 130.323494ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:52:02.823866Z","caller":"traceutil/trace.go:171","msg":"trace[1170473309] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"130.532851ms","start":"2026-04-17T18:52:02.693317Z","end":"2026-04-17T18:52:02.82385Z","steps":["trace[1170473309] 'process raft request' (duration: 130.296384ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:52:02.823905Z","caller":"traceutil/trace.go:171","msg":"trace[1058396713] linearizableReadLoop","detail":"{readStateIndex:5639; appliedIndex:5635; }","duration":"103.82949ms","start":"2026-04-17T18:52:02.720056Z","end":"2026-04-17T18:52:02.823885Z","steps":["trace[1058396713] 'read index received' (duration: 85.137508ms)","trace[1058396713] 'applied index is now lower than readState.Index' (duration: 18.691131ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:52:02.823968Z","caller":"traceutil/trace.go:171","msg":"trace[891063333] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"130.52819ms","start":"2026-04-17T18:52:02.693433Z","end":"2026-04-17T18:52:02.823961Z","steps":["trace[891063333] 'process raft request' (duration: 130.351085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:52:02.824072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.999974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-17T18:52:02.824113Z","caller":"traceutil/trace.go:171","msg":"trace[374429289] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5438; }","duration":"104.079467ms","start":"2026-04-17T18:52:02.72002Z","end":"2026-04-17T18:52:02.824099Z","steps":["trace[374429289] 'agreement among raft nodes before linearized reading' (duration: 103.949243ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:52:47.699183Z","caller":"traceutil/trace.go:171","msg":"trace[1092522211] linearizableReadLoop","detail":"{readStateIndex:6034; appliedIndex:6032; }","duration":"127.9969ms","start":"2026-04-17T18:52:47.571163Z","end":"2026-04-17T18:52:47.69916Z","steps":["trace[1092522211] 'read index received' (duration: 42.502772ms)","trace[1092522211] 'applied index is now lower than readState.Index' (duration: 85.493068ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:52:47.699225Z","caller":"traceutil/trace.go:171","msg":"trace[1253947288] transaction","detail":"{read_only:false; response_revision:5822; number_of_response:1; }","duration":"132.644123ms","start":"2026-04-17T18:52:47.566555Z","end":"2026-04-17T18:52:47.6992Z","steps":["trace[1253947288] 'process raft request' (duration: 132.457048ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:52:47.699361Z","caller":"traceutil/trace.go:171","msg":"trace[742625667] transaction","detail":"{read_only:false; response_revision:5823; number_of_response:1; }","duration":"125.887801ms","start":"2026-04-17T18:52:47.573462Z","end":"2026-04-17T18:52:47.699349Z","steps":["trace[742625667] 'process raft request' (duration: 125.660385ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:52:47.699357Z","caller":"traceutil/trace.go:171","msg":"trace[1512481923] transaction","detail":"{read_only:false; response_revision:5821; number_of_response:1; }","duration":"134.548418ms","start":"2026-04-17T18:52:47.564751Z","end":"2026-04-17T18:52:47.6993Z","steps":["trace[1512481923] 'process raft request' (duration: 48.977958ms)","trace[1512481923] 'compare' (duration: 85.063206ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:52:47.699728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.485864ms","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-17T18:52:47.699791Z","caller":"traceutil/trace.go:171","msg":"trace[325756414] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5823; }","duration":"128.67378ms","start":"2026-04-17T18:52:47.571101Z","end":"2026-04-17T18:52:47.699775Z","steps":["trace[325756414] 'agreement among raft nodes before linearized reading' (duration: 128.490925ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:53:01.22524Z","caller":"traceutil/trace.go:171","msg":"trace[1308220871] transaction","detail":"{read_only:false; response_revision:5917; number_of_response:1; }","duration":"123.599677ms","start":"2026-04-17T18:53:01.101614Z","end":"2026-04-17T18:53:01.225213Z","steps":["trace[1308220871] 'process raft request' (duration: 123.208565ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:53:21.413835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2991} {"level":"info","ts":"2026-04-17T18:53:21.518648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2991,"took":"103.161463ms","hash":910294827,"current-db-size-bytes":59125760,"current-db-size":"59 MB","current-db-size-in-use-bytes":34070528,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-17T18:53:21.518712Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":910294827,"revision":2991,"compact-revision":-1} {"level":"warn","ts":"2026-04-17T18:53:54.651893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.125221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T18:53:54.652Z","caller":"traceutil/trace.go:171","msg":"trace[1407764854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6305; }","duration":"104.290397ms","start":"2026-04-17T18:53:54.547691Z","end":"2026-04-17T18:53:54.651981Z","steps":["trace[1407764854] 'range keys from in-memory index tree' (duration: 104.019909ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:53:58.408633Z","caller":"traceutil/trace.go:171","msg":"trace[893710467] linearizableReadLoop","detail":"{readStateIndex:6549; appliedIndex:6548; }","duration":"124.06765ms","start":"2026-04-17T18:53:58.284538Z","end":"2026-04-17T18:53:58.408605Z","steps":["trace[893710467] 'read index received' (duration: 84.080479ms)","trace[893710467] 'applied index is now lower than readState.Index' (duration: 39.985801ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:53:58.408725Z","caller":"traceutil/trace.go:171","msg":"trace[1581615581] transaction","detail":"{read_only:false; response_revision:6321; number_of_response:1; }","duration":"127.800088ms","start":"2026-04-17T18:53:58.280887Z","end":"2026-04-17T18:53:58.408687Z","steps":["trace[1581615581] 'process raft request' (duration: 87.873028ms)","trace[1581615581] 'compare' (duration: 39.625051ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:53:58.408925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.356518ms","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-17T18:53:58.408978Z","caller":"traceutil/trace.go:171","msg":"trace[479831141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6321; }","duration":"124.475482ms","start":"2026-04-17T18:53:58.28449Z","end":"2026-04-17T18:53:58.408965Z","steps":["trace[479831141] 'agreement among raft nodes before linearized reading' (duration: 124.288717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:53:58.408975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.214615ms","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-17T18:53:58.409034Z","caller":"traceutil/trace.go:171","msg":"trace[125145481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6321; }","duration":"124.293437ms","start":"2026-04-17T18:53:58.284724Z","end":"2026-04-17T18:53:58.409018Z","steps":["trace[125145481] 'agreement among raft nodes before linearized reading' (duration: 124.128662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:53:58.409189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.388619ms","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-17T18:53:58.40923Z","caller":"traceutil/trace.go:171","msg":"trace[1117851790] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6321; }","duration":"104.476672ms","start":"2026-04-17T18:53:58.304741Z","end":"2026-04-17T18:53:58.409218Z","steps":["trace[1117851790] 'agreement among raft nodes before linearized reading' (duration: 104.370499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:06.78309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.647411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:54:06.783199Z","caller":"traceutil/trace.go:171","msg":"trace[2084770378] transaction","detail":"{read_only:false; response_revision:6365; number_of_response:1; }","duration":"182.90012ms","start":"2026-04-17T18:54:06.60028Z","end":"2026-04-17T18:54:06.78318Z","steps":["trace[2084770378] 'process raft request' (duration: 33.046603ms)","trace[2084770378] 'compare' (duration: 149.373613ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:54:06.793971Z","caller":"traceutil/trace.go:171","msg":"trace[562107863] transaction","detail":"{read_only:false; response_revision:6366; number_of_response:1; }","duration":"193.595835ms","start":"2026-04-17T18:54:06.600351Z","end":"2026-04-17T18:54:06.793947Z","steps":["trace[562107863] 'process raft request' (duration: 193.468631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:54:08.370357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.784997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:54:08.370657Z","caller":"traceutil/trace.go:171","msg":"trace[73572265] transaction","detail":"{read_only:false; response_revision:6369; number_of_response:1; }","duration":"195.388547ms","start":"2026-04-17T18:54:08.175191Z","end":"2026-04-17T18:54:08.37058Z","steps":["trace[73572265] 'process raft request' (duration: 67.27163ms)","trace[73572265] 'compare' (duration: 127.595772ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:57:53.455899Z","caller":"traceutil/trace.go:171","msg":"trace[914317336] transaction","detail":"{read_only:false; response_revision:8094; number_of_response:1; }","duration":"178.461018ms","start":"2026-04-17T18:57:53.277412Z","end":"2026-04-17T18:57:53.455873Z","steps":["trace[914317336] 'process raft request' (duration: 178.319454ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:57:53.46323Z","caller":"traceutil/trace.go:171","msg":"trace[210482845] transaction","detail":"{read_only:false; response_revision:8095; number_of_response:1; }","duration":"177.269415ms","start":"2026-04-17T18:57:53.285942Z","end":"2026-04-17T18:57:53.463211Z","steps":["trace[210482845] 'process raft request' (duration: 177.182101ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:58:03.645377Z","caller":"traceutil/trace.go:171","msg":"trace[1928194297] transaction","detail":"{read_only:false; response_revision:8147; number_of_response:1; }","duration":"125.506509ms","start":"2026-04-17T18:58:03.519832Z","end":"2026-04-17T18:58:03.645339Z","steps":["trace[1928194297] 'process raft request' (duration: 104.766468ms)","trace[1928194297] 'compare' (duration: 20.503174ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:58:04.375134Z","caller":"traceutil/trace.go:171","msg":"trace[420285407] linearizableReadLoop","detail":"{readStateIndex:8440; appliedIndex:8439; }","duration":"319.269953ms","start":"2026-04-17T18:58:04.055834Z","end":"2026-04-17T18:58:04.375104Z","steps":["trace[420285407] 'read index received' (duration: 304.737188ms)","trace[420285407] 'applied index is now lower than readState.Index' (duration: 14.531575ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:58:04.375164Z","caller":"traceutil/trace.go:171","msg":"trace[1658530939] transaction","detail":"{read_only:false; response_revision:8152; number_of_response:1; }","duration":"390.325279ms","start":"2026-04-17T18:58:03.984804Z","end":"2026-04-17T18:58:04.37513Z","steps":["trace[1658530939] 'process raft request' (duration: 375.704791ms)","trace[1658530939] 'compare' (duration: 14.494994ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:58:04.375314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:58:03.984789Z","time spent":"390.445873ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T18:58:04.37544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.894325ms","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-17T18:58:04.37544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.596282ms","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-17T18:58:04.375493Z","caller":"traceutil/trace.go:171","msg":"trace[2090083896] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8152; }","duration":"277.002937ms","start":"2026-04-17T18:58:04.098473Z","end":"2026-04-17T18:58:04.375476Z","steps":["trace[2090083896] 'agreement among raft nodes before linearized reading' (duration: 276.802732ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:58:04.375499Z","caller":"traceutil/trace.go:171","msg":"trace[84610494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8152; }","duration":"319.706565ms","start":"2026-04-17T18:58:04.055779Z","end":"2026-04-17T18:58:04.375485Z","steps":["trace[84610494] 'agreement among raft nodes before linearized reading' (duration: 319.431017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:58:04.37555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:58:04.055755Z","time spent":"319.783947ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T18:58:05.226557Z","caller":"traceutil/trace.go:171","msg":"trace[711402025] transaction","detail":"{read_only:false; response_revision:8156; number_of_response:1; }","duration":"251.723637ms","start":"2026-04-17T18:58:04.97481Z","end":"2026-04-17T18:58:05.226534Z","steps":["trace[711402025] 'process raft request' (duration: 251.548292ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:58:06.095811Z","caller":"traceutil/trace.go:171","msg":"trace[735929834] transaction","detail":"{read_only:false; response_revision:8163; number_of_response:1; }","duration":"135.231505ms","start":"2026-04-17T18:58:05.960551Z","end":"2026-04-17T18:58:06.095782Z","steps":["trace[735929834] 'process raft request' (duration: 135.088251ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:58:21.419759Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6079} {"level":"info","ts":"2026-04-17T18:58:21.515621Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6079,"took":"94.3648ms","hash":3108717291,"current-db-size-bytes":59125760,"current-db-size":"59 MB","current-db-size-in-use-bytes":23203840,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-17T18:58:21.515691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3108717291,"revision":6079,"compact-revision":2991} {"level":"warn","ts":"2026-04-17T18:58:32.355156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.325576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T18:58:32.355345Z","caller":"traceutil/trace.go:171","msg":"trace[974714708] transaction","detail":"{read_only:false; response_revision:8355; number_of_response:1; }","duration":"333.613072ms","start":"2026-04-17T18:58:32.021675Z","end":"2026-04-17T18:58:32.355288Z","steps":["trace[974714708] 'process raft request' (duration: 55.041499ms)","trace[974714708] 'compare' (duration: 278.041707ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:58:32.355537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:58:32.021647Z","time spent":"333.803377ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T18:58:32.35586Z","caller":"traceutil/trace.go:171","msg":"trace[1415225105] linearizableReadLoop","detail":"{readStateIndex:8653; appliedIndex:8651; }","duration":"323.008999ms","start":"2026-04-17T18:58:32.03283Z","end":"2026-04-17T18:58:32.355839Z","steps":["trace[1415225105] 'read index received' (duration: 19.403622ms)","trace[1415225105] 'applied index is now lower than readState.Index' (duration: 303.603247ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T18:58:32.356042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.207925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nz9zn\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-17T18:58:32.356086Z","caller":"traceutil/trace.go:171","msg":"trace[224536973] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nz9zn; range_end:; response_count:1; response_revision:8355; }","duration":"323.278737ms","start":"2026-04-17T18:58:32.032797Z","end":"2026-04-17T18:58:32.356076Z","steps":["trace[224536973] 'agreement among raft nodes before linearized reading' (duration: 323.134833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T18:58:32.356116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T18:58:32.032781Z","time spent":"323.328438ms","remote":"127.0.0.1:55774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-nz9zn\" "} {"level":"warn","ts":"2026-04-17T18:58:32.356449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.995704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-17T18:58:32.358951Z","caller":"traceutil/trace.go:171","msg":"trace[288636609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8356; }","duration":"138.070336ms","start":"2026-04-17T18:58:32.218436Z","end":"2026-04-17T18:58:32.356506Z","steps":["trace[288636609] 'agreement among raft nodes before linearized reading' (duration: 137.775908ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:58:32.577029Z","caller":"traceutil/trace.go:171","msg":"trace[933469045] transaction","detail":"{read_only:false; response_revision:8357; number_of_response:1; }","duration":"212.857239ms","start":"2026-04-17T18:58:32.364153Z","end":"2026-04-17T18:58:32.577011Z","steps":["trace[933469045] 'process raft request' (duration: 194.750452ms)","trace[933469045] 'compare' (duration: 17.575652ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:58:45.374182Z","caller":"traceutil/trace.go:171","msg":"trace[459961910] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8466; }","duration":"166.181098ms","start":"2026-04-17T18:58:45.207977Z","end":"2026-04-17T18:58:45.374158Z","steps":["trace[459961910] 'process raft request' (duration: 150.833139ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:58:45.39479Z","caller":"traceutil/trace.go:171","msg":"trace[498264564] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"184.508371ms","start":"2026-04-17T18:58:45.210258Z","end":"2026-04-17T18:58:45.394766Z","steps":["trace[498264564] 'process raft request' (duration: 184.365007ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:58:45.866816Z","caller":"traceutil/trace.go:171","msg":"trace[69054790] transaction","detail":"{read_only:false; response_revision:8469; number_of_response:1; }","duration":"103.638195ms","start":"2026-04-17T18:58:45.763159Z","end":"2026-04-17T18:58:45.866797Z","steps":["trace[69054790] 'process raft request' (duration: 103.517252ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:58:46.136369Z","caller":"traceutil/trace.go:171","msg":"trace[1421756846] transaction","detail":"{read_only:false; response_revision:8472; number_of_response:1; }","duration":"269.71517ms","start":"2026-04-17T18:58:45.866601Z","end":"2026-04-17T18:58:46.136316Z","steps":["trace[1421756846] 'process raft request' (duration: 269.100303ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:58:46.263938Z","caller":"traceutil/trace.go:171","msg":"trace[1621485318] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"122.172024ms","start":"2026-04-17T18:58:46.141737Z","end":"2026-04-17T18:58:46.263909Z","steps":["trace[1621485318] 'process raft request' (duration: 97.13316ms)","trace[1621485318] 'compare' (duration: 24.937411ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T18:59:05.525305Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002342.wal"} {"level":"info","ts":"2026-04-17T18:59:56.35598Z","caller":"traceutil/trace.go:171","msg":"trace[2115123345] transaction","detail":"{read_only:false; response_revision:9126; number_of_response:1; }","duration":"178.234346ms","start":"2026-04-17T18:59:56.177723Z","end":"2026-04-17T18:59:56.355957Z","steps":["trace[2115123345] 'process raft request' (duration: 178.108353ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T18:59:56.375157Z","caller":"traceutil/trace.go:171","msg":"trace[286824262] transaction","detail":"{read_only:false; response_revision:9127; number_of_response:1; }","duration":"197.346461ms","start":"2026-04-17T18:59:56.177784Z","end":"2026-04-17T18:59:56.37513Z","steps":["trace[286824262] 'process raft request' (duration: 197.101304ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:00:21.03243Z","caller":"traceutil/trace.go:171","msg":"trace[755871319] transaction","detail":"{read_only:false; response_revision:9368; number_of_response:1; }","duration":"104.215149ms","start":"2026-04-17T19:00:20.928185Z","end":"2026-04-17T19:00:21.0324Z","steps":["trace[755871319] 'process raft request' (duration: 104.052544ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:00:42.803089Z","caller":"traceutil/trace.go:171","msg":"trace[1918760016] transaction","detail":"{read_only:false; response_revision:9527; number_of_response:1; }","duration":"135.345124ms","start":"2026-04-17T19:00:42.667712Z","end":"2026-04-17T19:00:42.803057Z","steps":["trace[1918760016] 'process raft request' (duration: 135.151169ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:00:42.891513Z","caller":"traceutil/trace.go:171","msg":"trace[1782789976] transaction","detail":"{read_only:false; response_revision:9528; number_of_response:1; }","duration":"104.081355ms","start":"2026-04-17T19:00:42.78741Z","end":"2026-04-17T19:00:42.891492Z","steps":["trace[1782789976] 'process raft request' (duration: 76.447437ms)","trace[1782789976] 'compare' (duration: 27.428792ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:00:58.664338Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f9ba8c11acfbc749","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"warn","ts":"2026-04-17T19:00:58.743954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.990371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T19:00:58.744053Z","caller":"traceutil/trace.go:171","msg":"trace[2025776571] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:9664; }","duration":"104.145166ms","start":"2026-04-17T19:00:58.639884Z","end":"2026-04-17T19:00:58.744029Z","steps":["trace[2025776571] 'agreement among raft nodes before linearized reading' (duration: 24.5708ms)","trace[2025776571] 'count revisions from in-memory index tree' (duration: 79.429352ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:00:58.838332Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-17T19:00:58.838451Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-17T19:01:04.780974Z","caller":"traceutil/trace.go:171","msg":"trace[856121061] linearizableReadLoop","detail":"{readStateIndex:10032; appliedIndex:10031; }","duration":"137.746712ms","start":"2026-04-17T19:01:04.643198Z","end":"2026-04-17T19:01:04.780944Z","steps":["trace[856121061] 'read index received' (duration: 137.491265ms)","trace[856121061] 'applied index is now lower than readState.Index' (duration: 253.827µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T19:01:04.78126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.048971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-17T19:01:04.781348Z","caller":"traceutil/trace.go:171","msg":"trace[1316969761] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:9693; }","duration":"138.185576ms","start":"2026-04-17T19:01:04.64314Z","end":"2026-04-17T19:01:04.781326Z","steps":["trace[1316969761] 'agreement among raft nodes before linearized reading' (duration: 137.919998ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:05.046665Z","caller":"traceutil/trace.go:171","msg":"trace[1615312545] transaction","detail":"{read_only:false; response_revision:9696; number_of_response:1; }","duration":"105.748432ms","start":"2026-04-17T19:01:04.940879Z","end":"2026-04-17T19:01:05.046627Z","steps":["trace[1615312545] 'process raft request' (duration: 105.569137ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:09.431214Z","caller":"traceutil/trace.go:171","msg":"trace[2086855768] transaction","detail":"{read_only:false; response_revision:9723; number_of_response:1; }","duration":"113.606914ms","start":"2026-04-17T19:01:09.317568Z","end":"2026-04-17T19:01:09.431175Z","steps":["trace[2086855768] 'process raft request' (duration: 113.300296ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:01:09.54369Z","caller":"traceutil/trace.go:171","msg":"trace[1110362572] transaction","detail":"{read_only:false; response_revision:9725; number_of_response:1; }","duration":"100.75972ms","start":"2026-04-17T19:01:09.442893Z","end":"2026-04-17T19:01:09.543653Z","steps":["trace[1110362572] 'process raft request' (duration: 65.603448ms)","trace[1110362572] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/openstack/glance-ks-user-rs7w8; req_size:9144; } (duration: 34.887533ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:01:09.769275Z","caller":"traceutil/trace.go:171","msg":"trace[1586784332] transaction","detail":"{read_only:false; response_revision:9728; number_of_response:1; }","duration":"120.226284ms","start":"2026-04-17T19:01:09.649024Z","end":"2026-04-17T19:01:09.76925Z","steps":["trace[1586784332] 'process raft request' (duration: 119.491223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T19:02:17.125502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.040008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T19:02:17.125644Z","caller":"traceutil/trace.go:171","msg":"trace[311757087] transaction","detail":"{read_only:false; response_revision:10284; number_of_response:1; }","duration":"154.219462ms","start":"2026-04-17T19:02:16.971403Z","end":"2026-04-17T19:02:17.125623Z","steps":["trace[311757087] 'process raft request' (duration: 45.937958ms)","trace[311757087] 'compare' (duration: 107.823791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T19:02:58.755722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.040324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T19:02:58.755847Z","caller":"traceutil/trace.go:171","msg":"trace[128657443] linearizableReadLoop","detail":"{readStateIndex:10919; appliedIndex:10918; }","duration":"161.046197ms","start":"2026-04-17T19:02:58.594783Z","end":"2026-04-17T19:02:58.75583Z","steps":["trace[128657443] 'read index received' (duration: 17.729394ms)","trace[128657443] 'applied index is now lower than readState.Index' (duration: 143.315573ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T19:02:58.756112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.323944ms","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-17T19:02:58.755975Z","caller":"traceutil/trace.go:171","msg":"trace[2037064172] transaction","detail":"{read_only:false; response_revision:10551; number_of_response:1; }","duration":"218.03545ms","start":"2026-04-17T19:02:58.537871Z","end":"2026-04-17T19:02:58.755907Z","steps":["trace[2037064172] 'process raft request' (duration: 74.692387ms)","trace[2037064172] 'compare' (duration: 142.899809ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:02:58.756188Z","caller":"traceutil/trace.go:171","msg":"trace[814033140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10551; }","duration":"161.426657ms","start":"2026-04-17T19:02:58.594746Z","end":"2026-04-17T19:02:58.756173Z","steps":["trace[814033140] 'agreement among raft nodes before linearized reading' (duration: 161.17666ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T19:03:20.872553Z","caller":"traceutil/trace.go:171","msg":"trace[1761805916] transaction","detail":"{read_only:false; response_revision:10670; number_of_response:1; }","duration":"177.501687ms","start":"2026-04-17T19:03:20.695021Z","end":"2026-04-17T19:03:20.872523Z","steps":["trace[1761805916] 'process raft request' (duration: 120.804191ms)","trace[1761805916] 'compare' (duration: 56.481129ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T19:03:21.426286Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8249} {"level":"info","ts":"2026-04-17T19:03:21.506132Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8249,"took":"77.535079ms","hash":2941907335,"current-db-size-bytes":59125760,"current-db-size":"59 MB","current-db-size-in-use-bytes":25153536,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-17T19:03:21.506213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2941907335,"revision":8249,"compact-revision":6079} {"level":"info","ts":"2026-04-17T19:08:21.432536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10675} {"level":"info","ts":"2026-04-17T19:08:21.511069Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10675,"took":"76.224293ms","hash":821300745,"current-db-size-bytes":59125760,"current-db-size":"59 MB","current-db-size-in-use-bytes":20389888,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-17T19:08:21.511147Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":821300745,"revision":10675,"compact-revision":8249}