{"level":"warn","ts":"2026-05-07T23:18:53.05534Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:18:53.055438Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.170: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.170:2380","--initial-cluster=instance=https://199.19.213.170:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.170:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.170: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-05-07T23:18:53.055511Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:18:53.055523Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.170:2380"]} {"level":"info","ts":"2026-05-07T23:18:53.055544Z","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-05-07T23:18:53.056197Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"]} {"level":"info","ts":"2026-05-07T23:18:53.056277Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.170:2380"],"listen-peer-urls":["https://199.19.213.170:2380"],"advertise-client-urls":["https://199.19.213.170:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.170: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-05-07T23:18:53.061979Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.500025ms"} {"level":"info","ts":"2026-05-07T23:18:53.0739Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"80be33c6b0d5a492","cluster-id":"abad33c2eec87dc"} {"level":"info","ts":"2026-05-07T23:18:53.074014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T23:18:53.074041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became follower at term 0"} {"level":"info","ts":"2026-05-07T23:18:53.074052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 80be33c6b0d5a492 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T23:18:53.074061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became follower at term 1"} {"level":"info","ts":"2026-05-07T23:18:53.074106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=(9276909210893132946)"} {"level":"warn","ts":"2026-05-07T23:18:53.085461Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T23:18:53.08754Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T23:18:53.088861Z","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-05-07T23:18:53.091702Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"80be33c6b0d5a492","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T23:18:53.092835Z","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-05-07T23:18:53.092919Z","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-05-07T23:18:53.09293Z","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-05-07T23:18:53.092793Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"80be33c6b0d5a492","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T23:18:53.095359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 switched to configuration voters=(9276909210893132946)"} {"level":"info","ts":"2026-05-07T23:18:53.095457Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"abad33c2eec87dc","local-member-id":"80be33c6b0d5a492","added-peer-id":"80be33c6b0d5a492","added-peer-peer-urls":["https://199.19.213.170:2380"]} {"level":"info","ts":"2026-05-07T23:18:53.099926Z","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-05-07T23:18:53.100083Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.170:2380"} {"level":"info","ts":"2026-05-07T23:18:53.101729Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.170:2380"} {"level":"info","ts":"2026-05-07T23:18:53.102122Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"80be33c6b0d5a492","initial-advertise-peer-urls":["https://199.19.213.170:2380"],"listen-peer-urls":["https://199.19.213.170:2380"],"advertise-client-urls":["https://199.19.213.170:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.170:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T23:18:53.102204Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T23:18:53.7751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T23:18:53.775177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T23:18:53.775205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 received MsgPreVoteResp from 80be33c6b0d5a492 at term 1"} {"level":"info","ts":"2026-05-07T23:18:53.775226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became candidate at term 2"} {"level":"info","ts":"2026-05-07T23:18:53.775244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 received MsgVoteResp from 80be33c6b0d5a492 at term 2"} {"level":"info","ts":"2026-05-07T23:18:53.775261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"80be33c6b0d5a492 became leader at term 2"} {"level":"info","ts":"2026-05-07T23:18:53.775281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 80be33c6b0d5a492 elected leader 80be33c6b0d5a492 at term 2"} {"level":"info","ts":"2026-05-07T23:18:53.776355Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:18:53.777576Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"abad33c2eec87dc","local-member-id":"80be33c6b0d5a492","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:18:53.77762Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:18:53.777743Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:18:53.777796Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:18:53.777569Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"80be33c6b0d5a492","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.170:2379]}","request-path":"/0/members/80be33c6b0d5a492/attributes","cluster-id":"abad33c2eec87dc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T23:18:53.777833Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:18:53.778304Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T23:18:53.778384Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T23:18:53.779938Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T23:18:53.781063Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.170:2379"} {"level":"warn","ts":"2026-05-07T23:19:11.134896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.682714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:11.135179Z","caller":"traceutil/trace.go:171","msg":"trace[1479487163] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"165.656403ms","start":"2026-05-07T23:19:10.969498Z","end":"2026-05-07T23:19:11.135154Z","steps":["trace[1479487163] 'process raft request' (duration: 21.09658ms)","trace[1479487163] 'compare' (duration: 142.536133ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:11.137495Z","caller":"traceutil/trace.go:171","msg":"trace[583084444] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"167.570662ms","start":"2026-05-07T23:19:10.969875Z","end":"2026-05-07T23:19:11.137446Z","steps":["trace[583084444] 'process raft request' (duration: 167.453701ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:11.137713Z","caller":"traceutil/trace.go:171","msg":"trace[1440480870] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"167.905426ms","start":"2026-05-07T23:19:10.969775Z","end":"2026-05-07T23:19:11.13768Z","steps":["trace[1440480870] 'process raft request' (duration: 167.36172ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:11.395047Z","caller":"traceutil/trace.go:171","msg":"trace[1519317401] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:387; }","duration":"166.356377ms","start":"2026-05-07T23:19:11.228669Z","end":"2026-05-07T23:19:11.395025Z","steps":["trace[1519317401] 'read index received' (duration: 88.436606ms)","trace[1519317401] 'applied index is now lower than readState.Index' (duration: 77.918861ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:11.395141Z","caller":"traceutil/trace.go:171","msg":"trace[1444796551] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"250.412869ms","start":"2026-05-07T23:19:11.144695Z","end":"2026-05-07T23:19:11.395108Z","steps":["trace[1444796551] 'process raft request' (duration: 250.276528ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:11.395184Z","caller":"traceutil/trace.go:171","msg":"trace[2091386881] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"250.651441ms","start":"2026-05-07T23:19:11.144495Z","end":"2026-05-07T23:19:11.395146Z","steps":["trace[2091386881] 'process raft request' (duration: 172.662929ms)","trace[2091386881] 'compare' (duration: 77.677659ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:11.395284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.549469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-xb85p\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-05-07T23:19:11.395446Z","caller":"traceutil/trace.go:171","msg":"trace[489070991] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-xb85p; range_end:; response_count:1; response_revision:379; }","duration":"166.67803ms","start":"2026-05-07T23:19:11.228639Z","end":"2026-05-07T23:19:11.395317Z","steps":["trace[489070991] 'agreement among raft nodes before linearized reading' (duration: 166.498018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:11.396439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.186025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-05-07T23:19:11.396503Z","caller":"traceutil/trace.go:171","msg":"trace[504429908] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:380; }","duration":"164.303876ms","start":"2026-05-07T23:19:11.232185Z","end":"2026-05-07T23:19:11.396489Z","steps":["trace[504429908] 'agreement among raft nodes before linearized reading' (duration: 164.094994ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:11.397205Z","caller":"traceutil/trace.go:171","msg":"trace[1764279073] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"168.066094ms","start":"2026-05-07T23:19:11.229125Z","end":"2026-05-07T23:19:11.397191Z","steps":["trace[1764279073] 'process raft request' (duration: 167.079025ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:22.690351Z","caller":"traceutil/trace.go:171","msg":"trace[1531098228] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:447; }","duration":"121.279126ms","start":"2026-05-07T23:19:22.569045Z","end":"2026-05-07T23:19:22.690324Z","steps":["trace[1531098228] 'read index received' (duration: 73.993491ms)","trace[1531098228] 'applied index is now lower than readState.Index' (duration: 47.284405ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:22.690407Z","caller":"traceutil/trace.go:171","msg":"trace[1476877943] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"159.968374ms","start":"2026-05-07T23:19:22.530402Z","end":"2026-05-07T23:19:22.690371Z","steps":["trace[1476877943] 'process raft request' (duration: 112.723449ms)","trace[1476877943] 'compare' (duration: 47.040403ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:22.690543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.476328ms","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-05-07T23:19:22.690607Z","caller":"traceutil/trace.go:171","msg":"trace[790351507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:433; }","duration":"121.57542ms","start":"2026-05-07T23:19:22.569012Z","end":"2026-05-07T23:19:22.690587Z","steps":["trace[790351507] 'agreement among raft nodes before linearized reading' (duration: 121.431199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:25.739466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.425393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:25.739548Z","caller":"traceutil/trace.go:171","msg":"trace[712116444] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"622.36392ms","start":"2026-05-07T23:19:25.117169Z","end":"2026-05-07T23:19:25.739533Z","steps":["trace[712116444] 'process raft request' (duration: 375.811356ms)","trace[712116444] 'compare' (duration: 246.312092ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:25.739595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:25.117138Z","time spent":"622.436051ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:27.146899Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.37375583s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T23:19:27.160032Z","caller":"traceutil/trace.go:171","msg":"trace[1317863128] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.959429576s","start":"2026-05-07T23:19:25.200424Z","end":"2026-05-07T23:19:27.159853Z","steps":["trace[1317863128] 'process raft request' (duration: 1.946768863s)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:27.160117Z","caller":"traceutil/trace.go:171","msg":"trace[2061315033] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.95873707s","start":"2026-05-07T23:19:25.201338Z","end":"2026-05-07T23:19:27.160075Z","steps":["trace[2061315033] 'process raft request' (duration: 1.955476081s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.160184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:25.2004Z","time spent":"1.959699458s","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:27.160258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:25.201331Z","time spent":"1.958858291s","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:19:27.16323Z","caller":"traceutil/trace.go:171","msg":"trace[999690854] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:563; }","duration":"1.763633492s","start":"2026-05-07T23:19:25.39956Z","end":"2026-05-07T23:19:27.163193Z","steps":["trace[999690854] 'read index received' (duration: 93.503697ms)","trace[999690854] 'applied index is now lower than readState.Index' (duration: 1.670128785s)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:27.163302Z","caller":"traceutil/trace.go:171","msg":"trace[1203635839] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.799799749s","start":"2026-05-07T23:19:25.363483Z","end":"2026-05-07T23:19:27.163282Z","steps":["trace[1203635839] 'process raft request' (duration: 1.799504546s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.163347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.763790374s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:19:27.163375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:25.363455Z","time spent":"1.799891281s","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:19:27.163382Z","caller":"traceutil/trace.go:171","msg":"trace[1962872127] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:549; }","duration":"1.763864984s","start":"2026-05-07T23:19:25.399506Z","end":"2026-05-07T23:19:27.163371Z","steps":["trace[1962872127] 'agreement among raft nodes before linearized reading' (duration: 1.763783874s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.163406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:25.399478Z","time spent":"1.763922304s","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-05-07T23:19:27.165525Z","caller":"traceutil/trace.go:171","msg":"trace[434097532] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.37285973s","start":"2026-05-07T23:19:25.792647Z","end":"2026-05-07T23:19:27.165507Z","steps":["trace[434097532] 'process raft request' (duration: 1.372636238s)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:27.165633Z","caller":"traceutil/trace.go:171","msg":"trace[401065256] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.160598396s","start":"2026-05-07T23:19:26.005028Z","end":"2026-05-07T23:19:27.165627Z","steps":["trace[401065256] 'process raft request' (duration: 1.160422414s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.165676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:26.004988Z","time spent":"1.160667747s","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:19:27.165768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:25.792625Z","time spent":"1.373012821s","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:27.165847Z","caller":"traceutil/trace.go:171","msg":"trace[190039500] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.144724972s","start":"2026-05-07T23:19:26.021113Z","end":"2026-05-07T23:19:27.165838Z","steps":["trace[190039500] 'process raft request' (duration: 1.144362849s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.165891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:26.021097Z","time spent":"1.144771574s","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:27.165981Z","caller":"traceutil/trace.go:171","msg":"trace[570555137] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"562.694267ms","start":"2026-05-07T23:19:26.603279Z","end":"2026-05-07T23:19:27.165973Z","steps":["trace[570555137] 'process raft request' (duration: 562.225832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.166016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:26.603245Z","time spent":"562.753567ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:19:27.1661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.385381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:27.16612Z","caller":"traceutil/trace.go:171","msg":"trace[543245290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"101.450201ms","start":"2026-05-07T23:19:27.064663Z","end":"2026-05-07T23:19:27.166113Z","steps":["trace[543245290] 'agreement among raft nodes before linearized reading' (duration: 101.41868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.166294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.088359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-05-07T23:19:27.166319Z","caller":"traceutil/trace.go:171","msg":"trace[412214995] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"167.14403ms","start":"2026-05-07T23:19:26.999168Z","end":"2026-05-07T23:19:27.166312Z","steps":["trace[412214995] 'agreement among raft nodes before linearized reading' (duration: 167.03836ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:27.166385Z","caller":"traceutil/trace.go:171","msg":"trace[1868865113] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.563864732s","start":"2026-05-07T23:19:25.602506Z","end":"2026-05-07T23:19:27.166371Z","steps":["trace[1868865113] 'process raft request' (duration: 1.562672931s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.166432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.406087ms","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-05-07T23:19:27.166453Z","caller":"traceutil/trace.go:171","msg":"trace[12361518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:555; }","duration":"356.451338ms","start":"2026-05-07T23:19:26.809996Z","end":"2026-05-07T23:19:27.166448Z","steps":["trace[12361518] 'agreement among raft nodes before linearized reading' (duration: 356.395997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.166469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:26.809985Z","time spent":"356.480258ms","remote":"127.0.0.1:34674","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-05-07T23:19:27.166536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:25.602487Z","time spent":"1.563986293s","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:19:27.166581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.80382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-07T23:19:27.166603Z","caller":"traceutil/trace.go:171","msg":"trace[1037936825] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"367.85616ms","start":"2026-05-07T23:19:26.798741Z","end":"2026-05-07T23:19:27.166597Z","steps":["trace[1037936825] 'agreement among raft nodes before linearized reading' (duration: 367.793859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.166619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:26.798726Z","time spent":"367.88947ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-05-07T23:19:27.1667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.07646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.170\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T23:19:27.166721Z","caller":"traceutil/trace.go:171","msg":"trace[65633803] range","detail":"{range_begin:/registry/masterleases/199.19.213.170; range_end:; response_count:1; response_revision:555; }","duration":"380.09557ms","start":"2026-05-07T23:19:26.786619Z","end":"2026-05-07T23:19:27.166714Z","steps":["trace[65633803] 'agreement among raft nodes before linearized reading' (duration: 380.06158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.166737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:26.786554Z","time spent":"380.179151ms","remote":"127.0.0.1:34456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.170\" "} {"level":"warn","ts":"2026-05-07T23:19:27.165582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576107091s","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-05-07T23:19:27.172857Z","caller":"traceutil/trace.go:171","msg":"trace[2051082289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"1.583412278s","start":"2026-05-07T23:19:25.589415Z","end":"2026-05-07T23:19:27.172827Z","steps":["trace[2051082289] 'agreement among raft nodes before linearized reading' (duration: 1.576117762s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.172927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:25.589394Z","time spent":"1.583512788s","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T23:19:27.165612Z","caller":"traceutil/trace.go:171","msg":"trace[1542190559] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.363968009s","start":"2026-05-07T23:19:25.801636Z","end":"2026-05-07T23:19:27.165604Z","steps":["trace[1542190559] 'process raft request' (duration: 1.363790088s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.173073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:25.801616Z","time spent":"1.371388066s","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:19:27.16687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.715186ms","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-05-07T23:19:27.173299Z","caller":"traceutil/trace.go:171","msg":"trace[995861320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"457.158245ms","start":"2026-05-07T23:19:26.71611Z","end":"2026-05-07T23:19:27.173268Z","steps":["trace[995861320] 'agreement among raft nodes before linearized reading' (duration: 450.712447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.173375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:26.716089Z","time spent":"457.269295ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T23:19:27.166906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.25214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:27.173547Z","caller":"traceutil/trace.go:171","msg":"trace[1815762430] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"773.92836ms","start":"2026-05-07T23:19:26.399609Z","end":"2026-05-07T23:19:27.173537Z","steps":["trace[1815762430] 'agreement among raft nodes before linearized reading' (duration: 767.27622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.173575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:26.399589Z","time spent":"773.97709ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-07T23:19:27.166935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.462206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:27.173712Z","caller":"traceutil/trace.go:171","msg":"trace[1716256446] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"974.273756ms","start":"2026-05-07T23:19:26.19943Z","end":"2026-05-07T23:19:27.173704Z","steps":["trace[1716256446] 'agreement among raft nodes before linearized reading' (duration: 967.488216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.173763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:26.19941Z","time spent":"974.343257ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-07T23:19:27.166964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090448674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T23:19:27.173872Z","caller":"traceutil/trace.go:171","msg":"trace[348280949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"1.097405806s","start":"2026-05-07T23:19:26.076457Z","end":"2026-05-07T23:19:27.173862Z","steps":["trace[348280949] 'agreement among raft nodes before linearized reading' (duration: 1.090488564s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.173919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:26.076429Z","time spent":"1.097480756s","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T23:19:27.16702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.41824539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-fmfk9\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-05-07T23:19:27.173979Z","caller":"traceutil/trace.go:171","msg":"trace[940067729] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-fmfk9; range_end:; response_count:1; response_revision:555; }","duration":"1.425223034s","start":"2026-05-07T23:19:25.748748Z","end":"2026-05-07T23:19:27.173971Z","steps":["trace[940067729] 'agreement among raft nodes before linearized reading' (duration: 1.418225101s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.174001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:25.748735Z","time spent":"1.425260244s","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4911,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-fmfk9\" "} {"level":"warn","ts":"2026-05-07T23:19:27.167046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.572836353s","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-05-07T23:19:27.174338Z","caller":"traceutil/trace.go:171","msg":"trace[598889870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"1.580164238s","start":"2026-05-07T23:19:25.594167Z","end":"2026-05-07T23:19:27.174331Z","steps":["trace[598889870] 'agreement among raft nodes before linearized reading' (duration: 1.572862653s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:27.174372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:25.594144Z","time spent":"1.58021217s","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T23:19:28.400836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.316289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:28.401104Z","caller":"traceutil/trace.go:171","msg":"trace[423941221] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"778.059701ms","start":"2026-05-07T23:19:27.623017Z","end":"2026-05-07T23:19:28.401076Z","steps":["trace[423941221] 'process raft request' (duration: 438.42605ms)","trace[423941221] 'compare' (duration: 339.114687ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:28.401198Z","caller":"traceutil/trace.go:171","msg":"trace[900102059] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"773.193008ms","start":"2026-05-07T23:19:27.627976Z","end":"2026-05-07T23:19:28.401169Z","steps":["trace[900102059] 'process raft request' (duration: 772.957336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:28.401381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:27.627963Z","time spent":"773.337809ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:28.40142Z","caller":"traceutil/trace.go:171","msg":"trace[698599985] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:598; }","duration":"757.560888ms","start":"2026-05-07T23:19:27.64385Z","end":"2026-05-07T23:19:28.40141Z","steps":["trace[698599985] 'read index received' (duration: 417.502612ms)","trace[698599985] 'applied index is now lower than readState.Index' (duration: 340.057486ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:28.401483Z","caller":"traceutil/trace.go:171","msg":"trace[1546350420] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"580.296239ms","start":"2026-05-07T23:19:27.821181Z","end":"2026-05-07T23:19:28.401477Z","steps":["trace[1546350420] 'process raft request' (duration: 580.098106ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:28.40146Z","caller":"traceutil/trace.go:171","msg":"trace[951340922] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"763.173579ms","start":"2026-05-07T23:19:27.638273Z","end":"2026-05-07T23:19:28.401447Z","steps":["trace[951340922] 'process raft request' (duration: 762.783284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:28.401522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:27.821151Z","time spent":"580.351609ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:19:28.401244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:27.622949Z","time spent":"778.211982ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:28.401547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:27.638265Z","time spent":"763.252119ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:19:28.401718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.863551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-05-07T23:19:28.401746Z","caller":"traceutil/trace.go:171","msg":"trace[835892084] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:583; }","duration":"757.891301ms","start":"2026-05-07T23:19:27.643846Z","end":"2026-05-07T23:19:28.401737Z","steps":["trace[835892084] 'agreement among raft nodes before linearized reading' (duration: 757.75744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:28.401772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:27.643823Z","time spent":"757.943191ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151259,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-07T23:19:28.40185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.632616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-05-07T23:19:28.401895Z","caller":"traceutil/trace.go:171","msg":"trace[869943629] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:583; }","duration":"401.718206ms","start":"2026-05-07T23:19:28.000163Z","end":"2026-05-07T23:19:28.401881Z","steps":["trace[869943629] 'agreement among raft nodes before linearized reading' (duration: 401.579095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:28.40192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.231175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4171"} {"level":"warn","ts":"2026-05-07T23:19:28.40193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:28.000139Z","time spent":"401.783917ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-05-07T23:19:28.401971Z","caller":"traceutil/trace.go:171","msg":"trace[624910107] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:583; }","duration":"202.286116ms","start":"2026-05-07T23:19:28.19965Z","end":"2026-05-07T23:19:28.401936Z","steps":["trace[624910107] 'agreement among raft nodes before linearized reading' (duration: 202.226095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:28.402045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.385516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:28.402068Z","caller":"traceutil/trace.go:171","msg":"trace[23604793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"334.439456ms","start":"2026-05-07T23:19:28.067622Z","end":"2026-05-07T23:19:28.402061Z","steps":["trace[23604793] 'agreement among raft nodes before linearized reading' (duration: 334.409286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:28.402084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:28.067606Z","time spent":"334.474517ms","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:19:28.64077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.180915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:28.640849Z","caller":"traceutil/trace.go:171","msg":"trace[1436853627] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"163.929534ms","start":"2026-05-07T23:19:28.476907Z","end":"2026-05-07T23:19:28.640837Z","steps":["trace[1436853627] 'process raft request' (duration: 54.634308ms)","trace[1436853627] 'compare' (duration: 109.063614ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:28.64152Z","caller":"traceutil/trace.go:171","msg":"trace[12886744] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"158.187712ms","start":"2026-05-07T23:19:28.483313Z","end":"2026-05-07T23:19:28.641501Z","steps":["trace[12886744] 'read index received' (duration: 48.22331ms)","trace[12886744] 'applied index is now lower than readState.Index' (duration: 109.963572ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:28.641608Z","caller":"traceutil/trace.go:171","msg":"trace[1496629874] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"164.536277ms","start":"2026-05-07T23:19:28.477064Z","end":"2026-05-07T23:19:28.6416Z","steps":["trace[1496629874] 'process raft request' (duration: 164.333436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:28.641718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.434773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31145"} {"level":"info","ts":"2026-05-07T23:19:28.64175Z","caller":"traceutil/trace.go:171","msg":"trace[1255364602] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:586; }","duration":"158.509014ms","start":"2026-05-07T23:19:28.483233Z","end":"2026-05-07T23:19:28.641742Z","steps":["trace[1255364602] 'agreement among raft nodes before linearized reading' (duration: 158.372962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:29.418506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11858714512002094783,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T23:19:29.513313Z","caller":"traceutil/trace.go:171","msg":"trace[341830472] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"662.681924ms","start":"2026-05-07T23:19:28.850608Z","end":"2026-05-07T23:19:29.51329Z","steps":["trace[341830472] 'process raft request' (duration: 614.194754ms)","trace[341830472] 'compare' (duration: 48.279118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:29.513446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:28.850583Z","time spent":"662.793225ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:29.514924Z","caller":"traceutil/trace.go:171","msg":"trace[1922077100] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:611; }","duration":"596.742726ms","start":"2026-05-07T23:19:28.918154Z","end":"2026-05-07T23:19:29.514897Z","steps":["trace[1922077100] 'read index received' (duration: 546.54422ms)","trace[1922077100] 'applied index is now lower than readState.Index' (duration: 50.197316ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:29.51532Z","caller":"traceutil/trace.go:171","msg":"trace[902757482] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"617.235618ms","start":"2026-05-07T23:19:28.898067Z","end":"2026-05-07T23:19:29.515302Z","steps":["trace[902757482] 'process raft request' (duration: 616.398111ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:29.515409Z","caller":"traceutil/trace.go:171","msg":"trace[560743205] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"182.240009ms","start":"2026-05-07T23:19:29.33315Z","end":"2026-05-07T23:19:29.51539Z","steps":["trace[560743205] 'process raft request' (duration: 181.692944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:29.51543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:28.898042Z","time spent":"617.308339ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:29.515679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.517892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-05-07T23:19:29.515753Z","caller":"traceutil/trace.go:171","msg":"trace[1666556292] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:596; }","duration":"597.610703ms","start":"2026-05-07T23:19:28.918128Z","end":"2026-05-07T23:19:29.515739Z","steps":["trace[1666556292] 'agreement among raft nodes before linearized reading' (duration: 597.448282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:29.515788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:28.918114Z","time spent":"597.664803ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":15711,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-05-07T23:19:29.5158Z","caller":"traceutil/trace.go:171","msg":"trace[398344608] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"428.123373ms","start":"2026-05-07T23:19:29.087662Z","end":"2026-05-07T23:19:29.515786Z","steps":["trace[398344608] 'process raft request' (duration: 427.118445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:29.515869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:29.087644Z","time spent":"428.186785ms","remote":"127.0.0.1:34594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4559,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:29.515999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.938004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-07T23:19:29.516031Z","caller":"traceutil/trace.go:171","msg":"trace[1271355704] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:596; }","duration":"515.995804ms","start":"2026-05-07T23:19:29.000025Z","end":"2026-05-07T23:19:29.51602Z","steps":["trace[1271355704] 'agreement among raft nodes before linearized reading' (duration: 515.901834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:29.516061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:29.000009Z","time spent":"516.045115ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-05-07T23:19:29.516338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.270411ms","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-05-07T23:19:29.516373Z","caller":"traceutil/trace.go:171","msg":"trace[1567177502] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:596; }","duration":"324.310781ms","start":"2026-05-07T23:19:29.192051Z","end":"2026-05-07T23:19:29.516361Z","steps":["trace[1567177502] 'agreement among raft nodes before linearized reading' (duration: 324.212829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:29.5164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:29.192048Z","time spent":"324.345732ms","remote":"127.0.0.1:34674","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-05-07T23:19:29.516575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.595316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-05-07T23:19:29.516605Z","caller":"traceutil/trace.go:171","msg":"trace[1201795076] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:596; }","duration":"116.655056ms","start":"2026-05-07T23:19:29.39994Z","end":"2026-05-07T23:19:29.516595Z","steps":["trace[1201795076] 'agreement among raft nodes before linearized reading' (duration: 116.576096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:29.51677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.072948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:29.516823Z","caller":"traceutil/trace.go:171","msg":"trace[1651667880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"209.155978ms","start":"2026-05-07T23:19:29.307652Z","end":"2026-05-07T23:19:29.516808Z","steps":["trace[1651667880] 'agreement among raft nodes before linearized reading' (duration: 209.091707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:29.516906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.854376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T23:19:29.516941Z","caller":"traceutil/trace.go:171","msg":"trace[1404241988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"324.893106ms","start":"2026-05-07T23:19:29.192037Z","end":"2026-05-07T23:19:29.51693Z","steps":["trace[1404241988] 'agreement among raft nodes before linearized reading' (duration: 324.792745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:29.516973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:29.192035Z","time spent":"324.930626ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T23:19:29.517066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.796784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-05-07T23:19:29.517097Z","caller":"traceutil/trace.go:171","msg":"trace[500796423] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:596; }","duration":"317.861984ms","start":"2026-05-07T23:19:29.199225Z","end":"2026-05-07T23:19:29.517087Z","steps":["trace[500796423] 'agreement among raft nodes before linearized reading' (duration: 317.758053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:29.517124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:29.199207Z","time spent":"317.910505ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-05-07T23:19:29.517275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.225519ms","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-05-07T23:19:29.517322Z","caller":"traceutil/trace.go:171","msg":"trace[1402638544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"325.287509ms","start":"2026-05-07T23:19:29.192024Z","end":"2026-05-07T23:19:29.517312Z","steps":["trace[1402638544] 'agreement among raft nodes before linearized reading' (duration: 325.147638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:29.517344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:29.192022Z","time spent":"325.31851ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T23:19:29.517436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.425651ms","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-05-07T23:19:29.517453Z","caller":"traceutil/trace.go:171","msg":"trace[93274337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"325.465141ms","start":"2026-05-07T23:19:29.191983Z","end":"2026-05-07T23:19:29.517448Z","steps":["trace[93274337] 'agreement among raft nodes before linearized reading' (duration: 325.420831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:29.517465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:29.191968Z","time spent":"325.493762ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T23:19:29.517528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.493561ms","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-05-07T23:19:29.517543Z","caller":"traceutil/trace.go:171","msg":"trace[2134075386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:596; }","duration":"326.534711ms","start":"2026-05-07T23:19:29.191004Z","end":"2026-05-07T23:19:29.517539Z","steps":["trace[2134075386] 'agreement among raft nodes before linearized reading' (duration: 326.491421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:29.517555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:29.19099Z","time spent":"326.562151ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T23:19:30.059865Z","caller":"traceutil/trace.go:171","msg":"trace[1187909650] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"260.374082ms","start":"2026-05-07T23:19:29.799475Z","end":"2026-05-07T23:19:30.05985Z","steps":["trace[1187909650] 'read index received' (duration: 196.101473ms)","trace[1187909650] 'applied index is now lower than readState.Index' (duration: 64.270609ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:30.060315Z","caller":"traceutil/trace.go:171","msg":"trace[763031554] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"450.784324ms","start":"2026-05-07T23:19:29.609522Z","end":"2026-05-07T23:19:30.060306Z","steps":["trace[763031554] 'process raft request' (duration: 386.126691ms)","trace[763031554] 'compare' (duration: 64.069277ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:30.060467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:29.609493Z","time spent":"450.844984ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:30.06073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.263179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-05-07T23:19:30.060814Z","caller":"traceutil/trace.go:171","msg":"trace[477175699] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:602; }","duration":"261.323909ms","start":"2026-05-07T23:19:29.799423Z","end":"2026-05-07T23:19:30.060747Z","steps":["trace[477175699] 'agreement among raft nodes before linearized reading' (duration: 261.209388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:34.476964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.266647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:34.477056Z","caller":"traceutil/trace.go:171","msg":"trace[424842874] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"352.369932ms","start":"2026-05-07T23:19:34.124672Z","end":"2026-05-07T23:19:34.477042Z","steps":["trace[424842874] 'read index received' (duration: 111.761541ms)","trace[424842874] 'applied index is now lower than readState.Index' (duration: 240.607181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:34.477127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.448553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:34.477152Z","caller":"traceutil/trace.go:171","msg":"trace[1948442218] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:630; }","duration":"352.502243ms","start":"2026-05-07T23:19:34.124642Z","end":"2026-05-07T23:19:34.477144Z","steps":["trace[1948442218] 'agreement among raft nodes before linearized reading' (duration: 352.435332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:34.477176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:34.124626Z","time spent":"352.543163ms","remote":"127.0.0.1:42324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"info","ts":"2026-05-07T23:19:34.47719Z","caller":"traceutil/trace.go:171","msg":"trace[1865740764] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"352.733026ms","start":"2026-05-07T23:19:34.124418Z","end":"2026-05-07T23:19:34.477151Z","steps":["trace[1865740764] 'process raft request' (duration: 112.224017ms)","trace[1865740764] 'compare' (duration: 240.098756ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:34.47736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:34.124404Z","time spent":"352.891407ms","remote":"127.0.0.1:34786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:19:35.065684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.949301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ml4w7\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-07T23:19:35.06578Z","caller":"traceutil/trace.go:171","msg":"trace[136218649] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ml4w7; range_end:; response_count:1; response_revision:630; }","duration":"295.122633ms","start":"2026-05-07T23:19:34.770636Z","end":"2026-05-07T23:19:35.065759Z","steps":["trace[136218649] 'range keys from in-memory index tree' (duration: 294.758569ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:35.133839Z","caller":"traceutil/trace.go:171","msg":"trace[2147360388] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"243.075719ms","start":"2026-05-07T23:19:34.890735Z","end":"2026-05-07T23:19:35.133811Z","steps":["trace[2147360388] 'process raft request' (duration: 242.309963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:35.860542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:35.54587Z","time spent":"314.664263ms","remote":"127.0.0.1:42326","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-07T23:19:36.019576Z","caller":"traceutil/trace.go:171","msg":"trace[403581043] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"446.147911ms","start":"2026-05-07T23:19:35.573404Z","end":"2026-05-07T23:19:36.019552Z","steps":["trace[403581043] 'read index received' (duration: 445.937789ms)","trace[403581043] 'applied index is now lower than readState.Index' (duration: 209.232µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:36.019664Z","caller":"traceutil/trace.go:171","msg":"trace[1119396453] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"830.699141ms","start":"2026-05-07T23:19:35.188937Z","end":"2026-05-07T23:19:36.019637Z","steps":["trace[1119396453] 'process raft request' (duration: 830.422858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:36.019834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:35.188912Z","time spent":"830.810702ms","remote":"127.0.0.1:34958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:19:36.019855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.446843ms","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-05-07T23:19:36.019902Z","caller":"traceutil/trace.go:171","msg":"trace[1399057704] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:633; }","duration":"446.533544ms","start":"2026-05-07T23:19:35.573355Z","end":"2026-05-07T23:19:36.019888Z","steps":["trace[1399057704] 'agreement among raft nodes before linearized reading' (duration: 446.326992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:36.019942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:35.573326Z","time spent":"446.604885ms","remote":"127.0.0.1:34674","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-05-07T23:19:36.019995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.555938ms","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-05-07T23:19:36.020082Z","caller":"traceutil/trace.go:171","msg":"trace[1098223504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"444.670699ms","start":"2026-05-07T23:19:35.575382Z","end":"2026-05-07T23:19:36.020053Z","steps":["trace[1098223504] 'agreement among raft nodes before linearized reading' (duration: 444.422327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:36.020124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:35.575369Z","time spent":"444.74683ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T23:19:36.020111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.573975ms","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-05-07T23:19:36.020187Z","caller":"traceutil/trace.go:171","msg":"trace[1071914987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"379.678115ms","start":"2026-05-07T23:19:35.640488Z","end":"2026-05-07T23:19:36.020167Z","steps":["trace[1071914987] 'agreement among raft nodes before linearized reading' (duration: 379.455253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:36.020226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:35.64048Z","time spent":"379.738227ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T23:19:36.020281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.671145ms","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-05-07T23:19:36.020284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.944279ms","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-05-07T23:19:36.020309Z","caller":"traceutil/trace.go:171","msg":"trace[1302015772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"380.699485ms","start":"2026-05-07T23:19:35.639601Z","end":"2026-05-07T23:19:36.0203Z","steps":["trace[1302015772] 'agreement among raft nodes before linearized reading' (duration: 380.618975ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:36.020318Z","caller":"traceutil/trace.go:171","msg":"trace[1807414457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"379.02397ms","start":"2026-05-07T23:19:35.641284Z","end":"2026-05-07T23:19:36.020308Z","steps":["trace[1807414457] 'agreement among raft nodes before linearized reading' (duration: 378.92978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:36.020335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:35.63956Z","time spent":"380.769496ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T23:19:36.020345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:35.641261Z","time spent":"379.077661ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T23:19:36.391971Z","caller":"traceutil/trace.go:171","msg":"trace[240334698] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"364.373782ms","start":"2026-05-07T23:19:36.027578Z","end":"2026-05-07T23:19:36.391952Z","steps":["trace[240334698] 'process raft request' (duration: 364.225001ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:36.392143Z","caller":"traceutil/trace.go:171","msg":"trace[953095561] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"364.478482ms","start":"2026-05-07T23:19:36.027652Z","end":"2026-05-07T23:19:36.39213Z","steps":["trace[953095561] 'process raft request' (duration: 364.271071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:36.39226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:36.027565Z","time spent":"364.461523ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:36.39226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:36.027645Z","time spent":"364.540723ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:36.392405Z","caller":"traceutil/trace.go:171","msg":"trace[2011336699] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"364.659683ms","start":"2026-05-07T23:19:36.027721Z","end":"2026-05-07T23:19:36.392381Z","steps":["trace[2011336699] 'process raft request' (duration: 364.376941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:36.39254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:36.027694Z","time spent":"364.778375ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:36.392631Z","caller":"traceutil/trace.go:171","msg":"trace[281711008] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"364.23389ms","start":"2026-05-07T23:19:36.028387Z","end":"2026-05-07T23:19:36.392621Z","steps":["trace[281711008] 'process raft request' (duration: 363.922388ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:36.392612Z","caller":"traceutil/trace.go:171","msg":"trace[1160802034] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:653; }","duration":"360.038223ms","start":"2026-05-07T23:19:36.032545Z","end":"2026-05-07T23:19:36.392583Z","steps":["trace[1160802034] 'read index received' (duration: 22.14772ms)","trace[1160802034] 'applied index is now lower than readState.Index' (duration: 337.888733ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:36.392734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:36.028379Z","time spent":"364.304121ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:36.392758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.199465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:36.392827Z","caller":"traceutil/trace.go:171","msg":"trace[1163569741] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:638; }","duration":"360.294446ms","start":"2026-05-07T23:19:36.032514Z","end":"2026-05-07T23:19:36.392808Z","steps":["trace[1163569741] 'agreement among raft nodes before linearized reading' (duration: 360.163705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:36.392858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:36.032504Z","time spent":"360.348336ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-07T23:19:52.361427Z","caller":"traceutil/trace.go:171","msg":"trace[1135986888] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"295.798762ms","start":"2026-05-07T23:19:52.065603Z","end":"2026-05-07T23:19:52.361401Z","steps":["trace[1135986888] 'read index received' (duration: 295.691197ms)","trace[1135986888] 'applied index is now lower than readState.Index' (duration: 106.035µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:52.361645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.019251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:52.361772Z","caller":"traceutil/trace.go:171","msg":"trace[164996483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"296.243432ms","start":"2026-05-07T23:19:52.065509Z","end":"2026-05-07T23:19:52.361752Z","steps":["trace[164996483] 'agreement among raft nodes before linearized reading' (duration: 296.017671ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:52.361568Z","caller":"traceutil/trace.go:171","msg":"trace[698560197] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"489.019674ms","start":"2026-05-07T23:19:51.872464Z","end":"2026-05-07T23:19:52.361484Z","steps":["trace[698560197] 'process raft request' (duration: 488.742212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:52.362144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:51.872442Z","time spent":"489.505256ms","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:52.388355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.921104ms","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-05-07T23:19:52.388444Z","caller":"traceutil/trace.go:171","msg":"trace[314334782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:756; }","duration":"224.064931ms","start":"2026-05-07T23:19:52.164359Z","end":"2026-05-07T23:19:52.388424Z","steps":["trace[314334782] 'agreement among raft nodes before linearized reading' (duration: 223.831971ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:04.679825Z","caller":"traceutil/trace.go:171","msg":"trace[1114652341] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"155.280741ms","start":"2026-05-07T23:20:04.524523Z","end":"2026-05-07T23:20:04.679803Z","steps":["trace[1114652341] 'read index received' (duration: 155.042922ms)","trace[1114652341] 'applied index is now lower than readState.Index' (duration: 235.369µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:20:04.679917Z","caller":"traceutil/trace.go:171","msg":"trace[1989068241] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"224.539878ms","start":"2026-05-07T23:20:04.455347Z","end":"2026-05-07T23:20:04.679887Z","steps":["trace[1989068241] 'process raft request' (duration: 224.277008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:04.680056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.951677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-07T23:20:04.680024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.475068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:20:04.680096Z","caller":"traceutil/trace.go:171","msg":"trace[1342855983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:905; }","duration":"131.046741ms","start":"2026-05-07T23:20:04.549039Z","end":"2026-05-07T23:20:04.680086Z","steps":["trace[1342855983] 'agreement among raft nodes before linearized reading' (duration: 130.901755ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:04.680124Z","caller":"traceutil/trace.go:171","msg":"trace[1137857872] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:905; }","duration":"155.622914ms","start":"2026-05-07T23:20:04.524478Z","end":"2026-05-07T23:20:04.680101Z","steps":["trace[1137857872] 'agreement among raft nodes before linearized reading' (duration: 155.454108ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:08.911953Z","caller":"traceutil/trace.go:171","msg":"trace[328982535] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"140.617804ms","start":"2026-05-07T23:20:08.771319Z","end":"2026-05-07T23:20:08.911937Z","steps":["trace[328982535] 'process raft request' (duration: 82.46254ms)","trace[328982535] 'compare' (duration: 57.949357ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:20:08.912059Z","caller":"traceutil/trace.go:171","msg":"trace[763115375] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"140.031723ms","start":"2026-05-07T23:20:08.772022Z","end":"2026-05-07T23:20:08.912054Z","steps":["trace[763115375] 'process raft request' (duration: 139.866657ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:08.912162Z","caller":"traceutil/trace.go:171","msg":"trace[1693040298] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"140.100266ms","start":"2026-05-07T23:20:08.772057Z","end":"2026-05-07T23:20:08.912157Z","steps":["trace[1693040298] 'process raft request' (duration: 139.979412ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:21.019502Z","caller":"traceutil/trace.go:171","msg":"trace[1915569646] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1178; }","duration":"140.156503ms","start":"2026-05-07T23:20:20.8793Z","end":"2026-05-07T23:20:21.019456Z","steps":["trace[1915569646] 'read index received' (duration: 139.932416ms)","trace[1915569646] 'applied index is now lower than readState.Index' (duration: 223.257µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:20:21.019909Z","caller":"traceutil/trace.go:171","msg":"trace[1124570283] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"197.78552ms","start":"2026-05-07T23:20:20.822083Z","end":"2026-05-07T23:20:21.019869Z","steps":["trace[1124570283] 'process raft request' (duration: 197.220112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:21.020061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.6941ms","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-05-07T23:20:21.02018Z","caller":"traceutil/trace.go:171","msg":"trace[1933898232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1148; }","duration":"140.908207ms","start":"2026-05-07T23:20:20.87925Z","end":"2026-05-07T23:20:21.020158Z","steps":["trace[1933898232] 'agreement among raft nodes before linearized reading' (duration: 140.597327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:21.420151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.799741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:20:21.420584Z","caller":"traceutil/trace.go:171","msg":"trace[1550415397] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"396.664245ms","start":"2026-05-07T23:20:21.023716Z","end":"2026-05-07T23:20:21.42038Z","steps":["trace[1550415397] 'process raft request' (duration: 76.340818ms)","trace[1550415397] 'compare' (duration: 319.635555ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:20:21.420662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:21.023693Z","time spent":"396.943753ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:20:21.437314Z","caller":"traceutil/trace.go:171","msg":"trace[113338922] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1179; }","duration":"401.865551ms","start":"2026-05-07T23:20:21.035416Z","end":"2026-05-07T23:20:21.437281Z","steps":["trace[113338922] 'read index received' (duration: 64.654954ms)","trace[113338922] 'applied index is now lower than readState.Index' (duration: 337.209567ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:20:21.437378Z","caller":"traceutil/trace.go:171","msg":"trace[763411195] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"413.592375ms","start":"2026-05-07T23:20:21.023751Z","end":"2026-05-07T23:20:21.437343Z","steps":["trace[763411195] 'process raft request' (duration: 413.281635ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:21.437494Z","caller":"traceutil/trace.go:171","msg":"trace[842486029] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"387.807992ms","start":"2026-05-07T23:20:21.049629Z","end":"2026-05-07T23:20:21.437437Z","steps":["trace[842486029] 'process raft request' (duration: 387.560294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:21.437573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:21.023735Z","time spent":"413.708219ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:20:21.437779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.485543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:20:21.437838Z","caller":"traceutil/trace.go:171","msg":"trace[1245603357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1151; }","duration":"372.641459ms","start":"2026-05-07T23:20:21.065181Z","end":"2026-05-07T23:20:21.437822Z","steps":["trace[1245603357] 'agreement among raft nodes before linearized reading' (duration: 372.552246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:21.437881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:21.065149Z","time spent":"372.723422ms","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:20:21.437879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.437241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T23:20:21.437927Z","caller":"traceutil/trace.go:171","msg":"trace[1696972935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1151; }","duration":"402.538714ms","start":"2026-05-07T23:20:21.035375Z","end":"2026-05-07T23:20:21.437914Z","steps":["trace[1696972935] 'agreement among raft nodes before linearized reading' (duration: 402.051448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:21.437977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:21.03536Z","time spent":"402.608816ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T23:20:21.438276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.643017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T23:20:21.438484Z","caller":"traceutil/trace.go:171","msg":"trace[706849279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1151; }","duration":"402.76514ms","start":"2026-05-07T23:20:21.035572Z","end":"2026-05-07T23:20:21.438338Z","steps":["trace[706849279] 'agreement among raft nodes before linearized reading' (duration: 402.167632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:21.438536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:21.049592Z","time spent":"388.762214ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:20:21.438694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:21.035568Z","time spent":"403.003839ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T23:20:25.599908Z","caller":"traceutil/trace.go:171","msg":"trace[75737196] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"104.971966ms","start":"2026-05-07T23:20:25.494907Z","end":"2026-05-07T23:20:25.599879Z","steps":["trace[75737196] 'process raft request' (duration: 104.821751ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:25.599994Z","caller":"traceutil/trace.go:171","msg":"trace[705592085] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"105.45175ms","start":"2026-05-07T23:20:25.49451Z","end":"2026-05-07T23:20:25.599961Z","steps":["trace[705592085] 'process raft request' (duration: 97.564469ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:42.612302Z","caller":"traceutil/trace.go:171","msg":"trace[229181770] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"150.644251ms","start":"2026-05-07T23:20:42.461621Z","end":"2026-05-07T23:20:42.612265Z","steps":["trace[229181770] 'process raft request' (duration: 150.269871ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:45.462552Z","caller":"traceutil/trace.go:171","msg":"trace[2062488854] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"267.577607ms","start":"2026-05-07T23:20:45.194948Z","end":"2026-05-07T23:20:45.462526Z","steps":["trace[2062488854] 'process raft request' (duration: 267.406453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:45.899038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.632821ms","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-05-07T23:20:45.899103Z","caller":"traceutil/trace.go:171","msg":"trace[230442608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1416; }","duration":"174.726133ms","start":"2026-05-07T23:20:45.724361Z","end":"2026-05-07T23:20:45.899088Z","steps":["trace[230442608] 'range keys from in-memory index tree' (duration: 174.513888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:45.899388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.435388ms","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-05-07T23:20:45.899474Z","caller":"traceutil/trace.go:171","msg":"trace[51260025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1416; }","duration":"176.558381ms","start":"2026-05-07T23:20:45.722896Z","end":"2026-05-07T23:20:45.899454Z","steps":["trace[51260025] 'range keys from in-memory index tree' (duration: 176.243322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:10.801956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11858714512002097269,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T23:21:10.842601Z","caller":"traceutil/trace.go:171","msg":"trace[797356886] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"590.31997ms","start":"2026-05-07T23:21:10.252254Z","end":"2026-05-07T23:21:10.842574Z","steps":["trace[797356886] 'process raft request' (duration: 589.970153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:10.842846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:10.252232Z","time spent":"590.434593ms","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:10.851173Z","caller":"traceutil/trace.go:171","msg":"trace[1901586139] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1885; }","duration":"549.731353ms","start":"2026-05-07T23:21:10.301418Z","end":"2026-05-07T23:21:10.851149Z","steps":["trace[1901586139] 'read index received' (duration: 540.837392ms)","trace[1901586139] 'applied index is now lower than readState.Index' (duration: 8.893011ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:10.851396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.966538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-07T23:21:10.851511Z","caller":"traceutil/trace.go:171","msg":"trace[1678369187] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1821; }","duration":"550.131771ms","start":"2026-05-07T23:21:10.301364Z","end":"2026-05-07T23:21:10.851496Z","steps":["trace[1678369187] 'agreement among raft nodes before linearized reading' (duration: 549.905707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:10.851552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:10.301344Z","time spent":"550.196462ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-07T23:21:11.39928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.315342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:21:11.399468Z","caller":"traceutil/trace.go:171","msg":"trace[1016929129] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"551.520455ms","start":"2026-05-07T23:21:10.847921Z","end":"2026-05-07T23:21:11.399441Z","steps":["trace[1016929129] 'process raft request' (duration: 215.960797ms)","trace[1016929129] 'compare' (duration: 335.19966ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:11.399547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:10.847899Z","time spent":"551.611877ms","remote":"127.0.0.1:34690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:11.399765Z","caller":"traceutil/trace.go:171","msg":"trace[2104012134] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"551.281978ms","start":"2026-05-07T23:21:10.848439Z","end":"2026-05-07T23:21:11.399721Z","steps":["trace[2104012134] 'process raft request' (duration: 550.958572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:11.400011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:10.848431Z","time spent":"551.409591ms","remote":"127.0.0.1:34584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:11.400391Z","caller":"traceutil/trace.go:171","msg":"trace[305578400] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"551.860352ms","start":"2026-05-07T23:21:10.848513Z","end":"2026-05-07T23:21:11.400373Z","steps":["trace[305578400] 'process raft request' (duration: 551.144006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:11.400494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:10.848509Z","time spent":"551.931073ms","remote":"127.0.0.1:34584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:11.401447Z","caller":"traceutil/trace.go:171","msg":"trace[1211433853] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1887; }","duration":"550.182845ms","start":"2026-05-07T23:21:10.851245Z","end":"2026-05-07T23:21:11.401427Z","steps":["trace[1211433853] 'read index received' (duration: 212.651725ms)","trace[1211433853] 'applied index is now lower than readState.Index' (duration: 337.53017ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:11.40155Z","caller":"traceutil/trace.go:171","msg":"trace[1564032057] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"552.849752ms","start":"2026-05-07T23:21:10.848674Z","end":"2026-05-07T23:21:11.401524Z","steps":["trace[1564032057] 'process raft request' (duration: 552.633588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:11.401689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.53608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d5ec96e4-2c73-44aa-b42d-23f7c80cf6af\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-07T23:21:11.401693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:10.848669Z","time spent":"552.938604ms","remote":"127.0.0.1:34690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:11.401728Z","caller":"traceutil/trace.go:171","msg":"trace[737969085] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d5ec96e4-2c73-44aa-b42d-23f7c80cf6af; range_end:; response_count:1; response_revision:1825; }","duration":"555.614201ms","start":"2026-05-07T23:21:10.846102Z","end":"2026-05-07T23:21:11.401716Z","steps":["trace[737969085] 'agreement among raft nodes before linearized reading' (duration: 555.448038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:11.401763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:10.846083Z","time spent":"555.671233ms","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d5ec96e4-2c73-44aa-b42d-23f7c80cf6af\" "} {"level":"warn","ts":"2026-05-07T23:21:11.401816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.020392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-07T23:21:11.401887Z","caller":"traceutil/trace.go:171","msg":"trace[609326442] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1825; }","duration":"854.130514ms","start":"2026-05-07T23:21:10.547736Z","end":"2026-05-07T23:21:11.401867Z","steps":["trace[609326442] 'agreement among raft nodes before linearized reading' (duration: 853.887099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:11.401931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:10.547724Z","time spent":"854.198616ms","remote":"127.0.0.1:60360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-07T23:21:11.401981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.266578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d5ec96e4-2c73-44aa-b42d-23f7c80cf6af\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-07T23:21:11.402023Z","caller":"traceutil/trace.go:171","msg":"trace[186124014] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d5ec96e4-2c73-44aa-b42d-23f7c80cf6af; range_end:; response_count:1; response_revision:1825; }","duration":"855.308049ms","start":"2026-05-07T23:21:10.546706Z","end":"2026-05-07T23:21:11.402014Z","steps":["trace[186124014] 'agreement among raft nodes before linearized reading' (duration: 855.204677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:11.402052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:10.546644Z","time spent":"855.401081ms","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d5ec96e4-2c73-44aa-b42d-23f7c80cf6af\" "} {"level":"info","ts":"2026-05-07T23:21:11.435307Z","caller":"traceutil/trace.go:171","msg":"trace[999747671] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"520.331371ms","start":"2026-05-07T23:21:10.914952Z","end":"2026-05-07T23:21:11.435283Z","steps":["trace[999747671] 'process raft request' (duration: 520.244079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:11.435333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.734196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:21:11.435404Z","caller":"traceutil/trace.go:171","msg":"trace[1037358151] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1827; }","duration":"393.841527ms","start":"2026-05-07T23:21:11.041544Z","end":"2026-05-07T23:21:11.435386Z","steps":["trace[1037358151] 'agreement among raft nodes before linearized reading' (duration: 393.705935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:11.435418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:10.914932Z","time spent":"520.429824ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:11.435419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.426569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:21:11.435444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:11.041529Z","time spent":"393.905969ms","remote":"127.0.0.1:42324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-05-07T23:21:11.435508Z","caller":"traceutil/trace.go:171","msg":"trace[1011011910] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"580.644113ms","start":"2026-05-07T23:21:10.854854Z","end":"2026-05-07T23:21:11.435498Z","steps":["trace[1011011910] 'process raft request' (duration: 580.213334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:11.435555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:10.854831Z","time spent":"580.700423ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:11.436018Z","caller":"traceutil/trace.go:171","msg":"trace[1119804185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1827; }","duration":"370.827287ms","start":"2026-05-07T23:21:11.064654Z","end":"2026-05-07T23:21:11.435481Z","steps":["trace[1119804185] 'agreement among raft nodes before linearized reading' (duration: 370.687434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:11.436063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:11.064634Z","time spent":"371.421409ms","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T23:21:11.529012Z","caller":"traceutil/trace.go:171","msg":"trace[1227792870] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"119.066822ms","start":"2026-05-07T23:21:11.409915Z","end":"2026-05-07T23:21:11.528982Z","steps":["trace[1227792870] 'process raft request' (duration: 99.152909ms)","trace[1227792870] 'compare' (duration: 19.74624ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:17.612754Z","caller":"traceutil/trace.go:171","msg":"trace[711993032] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"150.180214ms","start":"2026-05-07T23:21:17.462536Z","end":"2026-05-07T23:21:17.612717Z","steps":["trace[711993032] 'process raft request' (duration: 150.004501ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:23.990479Z","caller":"traceutil/trace.go:171","msg":"trace[1607480241] linearizableReadLoop","detail":"{readStateIndex:1995; appliedIndex:1994; }","duration":"337.081015ms","start":"2026-05-07T23:21:23.653165Z","end":"2026-05-07T23:21:23.990246Z","steps":["trace[1607480241] 'read index received' (duration: 336.934442ms)","trace[1607480241] 'applied index is now lower than readState.Index' (duration: 144.693µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:23.990606Z","caller":"traceutil/trace.go:171","msg":"trace[888757203] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"351.593861ms","start":"2026-05-07T23:21:23.638981Z","end":"2026-05-07T23:21:23.990575Z","steps":["trace[888757203] 'process raft request' (duration: 351.106692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:23.990753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:23.638951Z","time spent":"351.718093ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:23.995251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.064723ms","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-05-07T23:21:23.99533Z","caller":"traceutil/trace.go:171","msg":"trace[382337379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1926; }","duration":"342.164526ms","start":"2026-05-07T23:21:23.653144Z","end":"2026-05-07T23:21:23.995309Z","steps":["trace[382337379] 'agreement among raft nodes before linearized reading' (duration: 337.443262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:23.995388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:23.653133Z","time spent":"342.242087ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T23:21:24.010106Z","caller":"traceutil/trace.go:171","msg":"trace[311341876] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"217.86764ms","start":"2026-05-07T23:21:23.792221Z","end":"2026-05-07T23:21:24.010089Z","steps":["trace[311341876] 'process raft request' (duration: 217.833969ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:24.010184Z","caller":"traceutil/trace.go:171","msg":"trace[454159033] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"289.528724ms","start":"2026-05-07T23:21:23.720637Z","end":"2026-05-07T23:21:24.010165Z","steps":["trace[454159033] 'process raft request' (duration: 289.31006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:24.010292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.924575ms","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-05-07T23:21:24.010325Z","caller":"traceutil/trace.go:171","msg":"trace[1829086556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1928; }","duration":"162.003667ms","start":"2026-05-07T23:21:23.848313Z","end":"2026-05-07T23:21:24.010316Z","steps":["trace[1829086556] 'agreement among raft nodes before linearized reading' (duration: 161.885445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:24.010303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.90156ms","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-05-07T23:21:24.010387Z","caller":"traceutil/trace.go:171","msg":"trace[1431698848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1928; }","duration":"220.025763ms","start":"2026-05-07T23:21:23.790332Z","end":"2026-05-07T23:21:24.010358Z","steps":["trace[1431698848] 'agreement among raft nodes before linearized reading' (duration: 219.779248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:46.598092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.085725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:21:46.598379Z","caller":"traceutil/trace.go:171","msg":"trace[1443318197] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"460.636412ms","start":"2026-05-07T23:21:46.137597Z","end":"2026-05-07T23:21:46.598234Z","steps":["trace[1443318197] 'process raft request' (duration: 169.316623ms)","trace[1443318197] 'compare' (duration: 290.76489ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:46.598454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:46.137575Z","time spent":"460.843496ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:46.622498Z","caller":"traceutil/trace.go:171","msg":"trace[1329002185] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"484.672724ms","start":"2026-05-07T23:21:46.137693Z","end":"2026-05-07T23:21:46.622473Z","steps":["trace[1329002185] 'process raft request' (duration: 484.504071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:46.62281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:46.137665Z","time spent":"484.962519ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:46.623065Z","caller":"traceutil/trace.go:171","msg":"trace[1192139934] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"485.242594ms","start":"2026-05-07T23:21:46.13781Z","end":"2026-05-07T23:21:46.623053Z","steps":["trace[1192139934] 'process raft request' (duration: 484.619773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:46.623126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:46.137785Z","time spent":"485.310005ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:46.623197Z","caller":"traceutil/trace.go:171","msg":"trace[918961018] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"484.308318ms","start":"2026-05-07T23:21:46.13885Z","end":"2026-05-07T23:21:46.623158Z","steps":["trace[918961018] 'process raft request' (duration: 484.004012ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:46.623262Z","caller":"traceutil/trace.go:171","msg":"trace[230187663] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"342.302252ms","start":"2026-05-07T23:21:46.28092Z","end":"2026-05-07T23:21:46.623223Z","steps":["trace[230187663] 'process raft request' (duration: 342.066118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:46.623376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:46.13884Z","time spent":"484.45355ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:46.623417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:46.2809Z","time spent":"342.440894ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:21:46.62355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.961898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T23:21:46.623727Z","caller":"traceutil/trace.go:171","msg":"trace[355176634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2070; }","duration":"413.137511ms","start":"2026-05-07T23:21:46.210522Z","end":"2026-05-07T23:21:46.62366Z","steps":["trace[355176634] 'agreement among raft nodes before linearized reading' (duration: 412.814076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:46.623826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:46.210506Z","time spent":"413.306744ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T23:21:46.623179Z","caller":"traceutil/trace.go:171","msg":"trace[320389687] linearizableReadLoop","detail":"{readStateIndex:2142; appliedIndex:2138; }","duration":"412.51371ms","start":"2026-05-07T23:21:46.210561Z","end":"2026-05-07T23:21:46.623074Z","steps":["trace[320389687] 'read index received' (duration: 96.417927ms)","trace[320389687] 'applied index is now lower than readState.Index' (duration: 316.094283ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:46.624126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.965358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T23:21:46.624172Z","caller":"traceutil/trace.go:171","msg":"trace[757304464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2070; }","duration":"413.0287ms","start":"2026-05-07T23:21:46.211129Z","end":"2026-05-07T23:21:46.624158Z","steps":["trace[757304464] 'agreement among raft nodes before linearized reading' (duration: 412.879557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:46.624209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:46.211122Z","time spent":"413.080601ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T23:21:47.635772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.987243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:21:47.635862Z","caller":"traceutil/trace.go:171","msg":"trace[753091019] linearizableReadLoop","detail":"{readStateIndex:2147; appliedIndex:2146; }","duration":"343.459275ms","start":"2026-05-07T23:21:47.292388Z","end":"2026-05-07T23:21:47.635848Z","steps":["trace[753091019] 'read index received' (duration: 48.471µs)","trace[753091019] 'applied index is now lower than readState.Index' (duration: 343.408874ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:21:47.635969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.575017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:21:47.635941Z","caller":"traceutil/trace.go:171","msg":"trace[1571129858] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"821.396629ms","start":"2026-05-07T23:21:46.814507Z","end":"2026-05-07T23:21:47.635903Z","steps":["trace[1571129858] 'process raft request' (duration: 248.13708ms)","trace[1571129858] 'compare' (duration: 572.703718ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:47.636004Z","caller":"traceutil/trace.go:171","msg":"trace[2024671314] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2073; }","duration":"343.646428ms","start":"2026-05-07T23:21:47.29235Z","end":"2026-05-07T23:21:47.635996Z","steps":["trace[2024671314] 'agreement among raft nodes before linearized reading' (duration: 343.532566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:47.636032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:47.292332Z","time spent":"343.693149ms","remote":"127.0.0.1:34696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:21:47.63614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:21:46.814487Z","time spent":"821.530871ms","remote":"127.0.0.1:34456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:21:48.302641Z","caller":"traceutil/trace.go:171","msg":"trace[69267446] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"183.217838ms","start":"2026-05-07T23:21:48.119376Z","end":"2026-05-07T23:21:48.302593Z","steps":["trace[69267446] 'process raft request' (duration: 182.937102ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:48.310759Z","caller":"traceutil/trace.go:171","msg":"trace[177151893] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"171.108118ms","start":"2026-05-07T23:21:48.139596Z","end":"2026-05-07T23:21:48.310704Z","steps":["trace[177151893] 'process raft request' (duration: 170.599669ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:45.093708Z","caller":"traceutil/trace.go:171","msg":"trace[2111531957] linearizableReadLoop","detail":"{readStateIndex:2521; appliedIndex:2520; }","duration":"145.290644ms","start":"2026-05-07T23:22:44.948398Z","end":"2026-05-07T23:22:45.093689Z","steps":["trace[2111531957] 'read index received' (duration: 140.544406ms)","trace[2111531957] 'applied index is now lower than readState.Index' (duration: 4.745308ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:45.093845Z","caller":"traceutil/trace.go:171","msg":"trace[171808154] transaction","detail":"{read_only:false; response_revision:2434; number_of_response:1; }","duration":"146.020284ms","start":"2026-05-07T23:22:44.947792Z","end":"2026-05-07T23:22:45.093812Z","steps":["trace[171808154] 'process raft request' (duration: 141.203936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:45.09389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.471376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-05-07T23:22:45.093917Z","caller":"traceutil/trace.go:171","msg":"trace[792219413] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2434; }","duration":"145.540208ms","start":"2026-05-07T23:22:44.948369Z","end":"2026-05-07T23:22:45.093909Z","steps":["trace[792219413] 'agreement among raft nodes before linearized reading' (duration: 145.426126ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:45.094097Z","caller":"traceutil/trace.go:171","msg":"trace[2072630735] transaction","detail":"{read_only:false; response_revision:2436; number_of_response:1; }","duration":"143.166773ms","start":"2026-05-07T23:22:44.950922Z","end":"2026-05-07T23:22:45.094089Z","steps":["trace[2072630735] 'process raft request' (duration: 143.072832ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:45.094102Z","caller":"traceutil/trace.go:171","msg":"trace[557162955] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"142.284739ms","start":"2026-05-07T23:22:44.951795Z","end":"2026-05-07T23:22:45.09408Z","steps":["trace[557162955] 'process raft request' (duration: 142.241048ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:45.094202Z","caller":"traceutil/trace.go:171","msg":"trace[646433796] transaction","detail":"{read_only:false; response_revision:2435; number_of_response:1; }","duration":"143.281715ms","start":"2026-05-07T23:22:44.950843Z","end":"2026-05-07T23:22:45.094124Z","steps":["trace[646433796] 'process raft request' (duration: 143.025131ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:52.753709Z","caller":"traceutil/trace.go:171","msg":"trace[1045401835] transaction","detail":"{read_only:false; response_revision:2522; number_of_response:1; }","duration":"197.098527ms","start":"2026-05-07T23:22:52.55658Z","end":"2026-05-07T23:22:52.753679Z","steps":["trace[1045401835] 'process raft request' (duration: 196.882334ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:52.759776Z","caller":"traceutil/trace.go:171","msg":"trace[1664997594] linearizableReadLoop","detail":"{readStateIndex:2612; appliedIndex:2610; }","duration":"150.420665ms","start":"2026-05-07T23:22:52.609337Z","end":"2026-05-07T23:22:52.759758Z","steps":["trace[1664997594] 'read index received' (duration: 144.213787ms)","trace[1664997594] 'applied index is now lower than readState.Index' (duration: 6.206088ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:52.760016Z","caller":"traceutil/trace.go:171","msg":"trace[1358716781] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"202.486263ms","start":"2026-05-07T23:22:52.557521Z","end":"2026-05-07T23:22:52.760007Z","steps":["trace[1358716781] 'process raft request' (duration: 201.144453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:52.760172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.938363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:22:52.760195Z","caller":"traceutil/trace.go:171","msg":"trace[1023604585] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2523; }","duration":"151.020493ms","start":"2026-05-07T23:22:52.609168Z","end":"2026-05-07T23:22:52.760188Z","steps":["trace[1023604585] 'agreement among raft nodes before linearized reading' (duration: 150.963262ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:25.0652Z","caller":"traceutil/trace.go:171","msg":"trace[1610856469] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"186.684204ms","start":"2026-05-07T23:23:24.878479Z","end":"2026-05-07T23:23:25.065163Z","steps":["trace[1610856469] 'process raft request' (duration: 186.548522ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:25.33803Z","caller":"traceutil/trace.go:171","msg":"trace[139899678] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"457.75417ms","start":"2026-05-07T23:23:24.880252Z","end":"2026-05-07T23:23:25.338006Z","steps":["trace[139899678] 'process raft request' (duration: 456.907119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:25.338296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:24.880201Z","time spent":"457.908032ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:23:25.338287Z","caller":"traceutil/trace.go:171","msg":"trace[1541442339] linearizableReadLoop","detail":"{readStateIndex:2797; appliedIndex:2794; }","duration":"274.033186ms","start":"2026-05-07T23:23:25.064222Z","end":"2026-05-07T23:23:25.338256Z","steps":["trace[1541442339] 'read index received' (duration: 804.86µs)","trace[1541442339] 'applied index is now lower than readState.Index' (duration: 273.225866ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:23:25.338636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.910026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:23:25.339158Z","caller":"traceutil/trace.go:171","msg":"trace[1281986264] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:2701; }","duration":"242.456863ms","start":"2026-05-07T23:23:25.09668Z","end":"2026-05-07T23:23:25.339137Z","steps":["trace[1281986264] 'agreement among raft nodes before linearized reading' (duration: 241.895115ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:25.339205Z","caller":"traceutil/trace.go:171","msg":"trace[365157894] transaction","detail":"{read_only:false; response_revision:2701; number_of_response:1; }","duration":"358.255821ms","start":"2026-05-07T23:23:24.980897Z","end":"2026-05-07T23:23:25.339153Z","steps":["trace[365157894] 'process raft request' (duration: 357.052644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:25.340141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:24.980869Z","time spent":"359.164522ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:23:25.339694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.732895ms","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-05-07T23:23:25.34027Z","caller":"traceutil/trace.go:171","msg":"trace[2108790906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2701; }","duration":"205.323342ms","start":"2026-05-07T23:23:25.134931Z","end":"2026-05-07T23:23:25.340254Z","steps":["trace[2108790906] 'agreement among raft nodes before linearized reading' (duration: 204.684574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:25.338455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.213919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:23:25.340404Z","caller":"traceutil/trace.go:171","msg":"trace[384573838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2701; }","duration":"276.222566ms","start":"2026-05-07T23:23:25.064173Z","end":"2026-05-07T23:23:25.340396Z","steps":["trace[384573838] 'agreement among raft nodes before linearized reading' (duration: 274.170528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:25.699856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.360116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:23:25.699986Z","caller":"traceutil/trace.go:171","msg":"trace[22039262] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"355.608025ms","start":"2026-05-07T23:23:25.344353Z","end":"2026-05-07T23:23:25.699961Z","steps":["trace[22039262] 'process raft request' (duration: 65.054365ms)","trace[22039262] 'compare' (duration: 290.096553ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:23:25.700067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:25.344331Z","time spent":"355.693806ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:23:26.306546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.910337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:23:26.30683Z","caller":"traceutil/trace.go:171","msg":"trace[1224815501] transaction","detail":"{read_only:false; response_revision:2708; number_of_response:1; }","duration":"289.813174ms","start":"2026-05-07T23:23:26.017Z","end":"2026-05-07T23:23:26.306813Z","steps":["trace[1224815501] 'process raft request' (duration: 289.776294ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:26.307063Z","caller":"traceutil/trace.go:171","msg":"trace[663821753] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"961.248718ms","start":"2026-05-07T23:23:25.345781Z","end":"2026-05-07T23:23:26.307029Z","steps":["trace[663821753] 'process raft request' (duration: 960.899243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:26.307316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.806594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:23:26.307366Z","caller":"traceutil/trace.go:171","msg":"trace[2130378692] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2708; }","duration":"486.910025ms","start":"2026-05-07T23:23:25.820447Z","end":"2026-05-07T23:23:26.307357Z","steps":["trace[2130378692] 'agreement among raft nodes before linearized reading' (duration: 486.717993ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:23:26.307363Z","caller":"traceutil/trace.go:171","msg":"trace[559911157] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"961.948327ms","start":"2026-05-07T23:23:25.345393Z","end":"2026-05-07T23:23:26.307341Z","steps":["trace[559911157] 'process raft request' (duration: 607.163989ms)","trace[559911157] 'compare' (duration: 353.821066ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:23:26.307368Z","caller":"traceutil/trace.go:171","msg":"trace[1273521589] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"961.640663ms","start":"2026-05-07T23:23:25.345712Z","end":"2026-05-07T23:23:26.307353Z","steps":["trace[1273521589] 'process raft request' (duration: 960.921274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:26.307398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:25.820425Z","time spent":"486.964206ms","remote":"127.0.0.1:34660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-05-07T23:23:26.307438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:25.345704Z","time spent":"961.696314ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:23:26.307494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:25.34536Z","time spent":"962.050249ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:23:26.307566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:25.345774Z","time spent":"961.536072ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:23:26.307063Z","caller":"traceutil/trace.go:171","msg":"trace[2133708796] transaction","detail":"{read_only:false; response_revision:2707; number_of_response:1; }","duration":"960.724751ms","start":"2026-05-07T23:23:25.346306Z","end":"2026-05-07T23:23:26.307031Z","steps":["trace[2133708796] 'process raft request' (duration: 960.435257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:26.307755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:25.346296Z","time spent":"961.4195ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:23:26.307131Z","caller":"traceutil/trace.go:171","msg":"trace[319969899] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"961.219238ms","start":"2026-05-07T23:23:25.345902Z","end":"2026-05-07T23:23:26.307121Z","steps":["trace[319969899] 'process raft request' (duration: 960.807383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:26.307885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:25.345896Z","time spent":"961.957249ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:23:26.307105Z","caller":"traceutil/trace.go:171","msg":"trace[319003492] linearizableReadLoop","detail":"{readStateIndex:2803; appliedIndex:2798; }","duration":"486.577351ms","start":"2026-05-07T23:23:25.820497Z","end":"2026-05-07T23:23:26.307075Z","steps":["trace[319003492] 'read index received' (duration: 132.001866ms)","trace[319003492] 'applied index is now lower than readState.Index' (duration: 354.572405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:23:26.308147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.789167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:23:26.310234Z","caller":"traceutil/trace.go:171","msg":"trace[157520368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2708; }","duration":"245.896724ms","start":"2026-05-07T23:23:26.064313Z","end":"2026-05-07T23:23:26.310209Z","steps":["trace[157520368] 'agreement among raft nodes before linearized reading' (duration: 243.789176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:26.308189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.261379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ad6c02878e05ed\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-07T23:23:26.31043Z","caller":"traceutil/trace.go:171","msg":"trace[343748057] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ad6c02878e05ed; range_end:; response_count:1; response_revision:2708; }","duration":"306.542399ms","start":"2026-05-07T23:23:26.003868Z","end":"2026-05-07T23:23:26.31041Z","steps":["trace[343748057] 'agreement among raft nodes before linearized reading' (duration: 304.199479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:23:26.310582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:23:26.003849Z","time spent":"306.652961ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":804,"request content":"key:\"/registry/events/auth-system/keycloak-0.18ad6c02878e05ed\" "} {"level":"info","ts":"2026-05-07T23:24:39.826294Z","caller":"traceutil/trace.go:171","msg":"trace[397680604] transaction","detail":"{read_only:false; response_revision:3091; number_of_response:1; }","duration":"117.096335ms","start":"2026-05-07T23:24:39.709165Z","end":"2026-05-07T23:24:39.826261Z","steps":["trace[397680604] 'process raft request' (duration: 116.869809ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:39.82781Z","caller":"traceutil/trace.go:171","msg":"trace[1632378189] transaction","detail":"{read_only:false; response_revision:3092; number_of_response:1; }","duration":"118.450749ms","start":"2026-05-07T23:24:39.709331Z","end":"2026-05-07T23:24:39.827781Z","steps":["trace[1632378189] 'process raft request' (duration: 118.304396ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:55.614907Z","caller":"traceutil/trace.go:171","msg":"trace[694990520] transaction","detail":"{read_only:false; response_revision:3172; number_of_response:1; }","duration":"163.056464ms","start":"2026-05-07T23:24:55.451824Z","end":"2026-05-07T23:24:55.61488Z","steps":["trace[694990520] 'process raft request' (duration: 162.902111ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:38.890851Z","caller":"traceutil/trace.go:171","msg":"trace[928112555] transaction","detail":"{read_only:false; response_revision:3484; number_of_response:1; }","duration":"148.778388ms","start":"2026-05-07T23:25:38.742052Z","end":"2026-05-07T23:25:38.890831Z","steps":["trace[928112555] 'process raft request' (duration: 148.653505ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:54.287233Z","caller":"traceutil/trace.go:171","msg":"trace[1638564395] transaction","detail":"{read_only:false; response_revision:3572; number_of_response:1; }","duration":"124.539851ms","start":"2026-05-07T23:25:54.162662Z","end":"2026-05-07T23:25:54.287202Z","steps":["trace[1638564395] 'process raft request' (duration: 124.381048ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:54.302977Z","caller":"traceutil/trace.go:171","msg":"trace[1734511628] linearizableReadLoop","detail":"{readStateIndex:3703; appliedIndex:3701; }","duration":"119.558242ms","start":"2026-05-07T23:25:54.183399Z","end":"2026-05-07T23:25:54.302957Z","steps":["trace[1734511628] 'read index received' (duration: 103.738252ms)","trace[1734511628] 'applied index is now lower than readState.Index' (duration: 15.81906ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:25:54.303107Z","caller":"traceutil/trace.go:171","msg":"trace[1082332204] transaction","detail":"{read_only:false; response_revision:3573; number_of_response:1; }","duration":"135.254397ms","start":"2026-05-07T23:25:54.167829Z","end":"2026-05-07T23:25:54.303083Z","steps":["trace[1082332204] 'process raft request' (duration: 135.023843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:54.303329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.898478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:25:54.303434Z","caller":"traceutil/trace.go:171","msg":"trace[491385123] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:3573; }","duration":"120.062131ms","start":"2026-05-07T23:25:54.183355Z","end":"2026-05-07T23:25:54.303417Z","steps":["trace[491385123] 'agreement among raft nodes before linearized reading' (duration: 119.821716ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:55.938306Z","caller":"traceutil/trace.go:171","msg":"trace[1364402384] transaction","detail":"{read_only:false; response_revision:3580; number_of_response:1; }","duration":"101.190706ms","start":"2026-05-07T23:25:55.837092Z","end":"2026-05-07T23:25:55.938283Z","steps":["trace[1364402384] 'process raft request' (duration: 101.058543ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:04.103852Z","caller":"traceutil/trace.go:171","msg":"trace[1013000543] transaction","detail":"{read_only:false; response_revision:3622; number_of_response:1; }","duration":"114.879916ms","start":"2026-05-07T23:26:03.988945Z","end":"2026-05-07T23:26:04.103825Z","steps":["trace[1013000543] 'process raft request' (duration: 114.738963ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:04.132829Z","caller":"traceutil/trace.go:171","msg":"trace[1000979854] transaction","detail":"{read_only:false; response_revision:3623; number_of_response:1; }","duration":"123.423794ms","start":"2026-05-07T23:26:04.009376Z","end":"2026-05-07T23:26:04.1328Z","steps":["trace[1000979854] 'process raft request' (duration: 123.307342ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:13.084108Z","caller":"traceutil/trace.go:171","msg":"trace[500478037] transaction","detail":"{read_only:false; response_revision:3670; number_of_response:1; }","duration":"183.008495ms","start":"2026-05-07T23:26:12.901042Z","end":"2026-05-07T23:26:13.084051Z","steps":["trace[500478037] 'process raft request' (duration: 179.998227ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:26.680425Z","caller":"traceutil/trace.go:171","msg":"trace[1829501873] transaction","detail":"{read_only:false; response_revision:3745; number_of_response:1; }","duration":"262.806947ms","start":"2026-05-07T23:26:26.417595Z","end":"2026-05-07T23:26:26.680402Z","steps":["trace[1829501873] 'process raft request' (duration: 262.642824ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:26.715135Z","caller":"traceutil/trace.go:171","msg":"trace[608997825] transaction","detail":"{read_only:false; response_revision:3747; number_of_response:1; }","duration":"178.841223ms","start":"2026-05-07T23:26:26.536272Z","end":"2026-05-07T23:26:26.715113Z","steps":["trace[608997825] 'process raft request' (duration: 178.722421ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:26.715131Z","caller":"traceutil/trace.go:171","msg":"trace[95863542] transaction","detail":"{read_only:false; response_revision:3746; number_of_response:1; }","duration":"188.130867ms","start":"2026-05-07T23:26:26.526968Z","end":"2026-05-07T23:26:26.715099Z","steps":["trace[95863542] 'process raft request' (duration: 187.855892ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:27.128876Z","caller":"traceutil/trace.go:171","msg":"trace[537129748] transaction","detail":"{read_only:false; response_revision:3750; number_of_response:1; }","duration":"138.279504ms","start":"2026-05-07T23:26:26.990578Z","end":"2026-05-07T23:26:27.128857Z","steps":["trace[537129748] 'process raft request' (duration: 138.132211ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:29.025139Z","caller":"traceutil/trace.go:171","msg":"trace[1268321212] transaction","detail":"{read_only:false; response_revision:3759; number_of_response:1; }","duration":"304.520831ms","start":"2026-05-07T23:26:28.720586Z","end":"2026-05-07T23:26:29.025107Z","steps":["trace[1268321212] 'process raft request' (duration: 304.288787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:29.02537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:28.720563Z","time spent":"304.707104ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:26:29.026015Z","caller":"traceutil/trace.go:171","msg":"trace[1760956245] transaction","detail":"{read_only:false; response_revision:3760; number_of_response:1; }","duration":"304.897728ms","start":"2026-05-07T23:26:28.721081Z","end":"2026-05-07T23:26:29.025979Z","steps":["trace[1760956245] 'process raft request' (duration: 304.676584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:29.026291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:28.721055Z","time spent":"305.115452ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:26:31.468173Z","caller":"traceutil/trace.go:171","msg":"trace[1716409750] transaction","detail":"{read_only:false; response_revision:3786; number_of_response:1; }","duration":"148.328272ms","start":"2026-05-07T23:26:31.319815Z","end":"2026-05-07T23:26:31.468143Z","steps":["trace[1716409750] 'process raft request' (duration: 70.371695ms)","trace[1716409750] 'compare' (duration: 77.804654ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:31.468527Z","caller":"traceutil/trace.go:171","msg":"trace[1678493554] transaction","detail":"{read_only:false; response_revision:3787; number_of_response:1; }","duration":"148.581385ms","start":"2026-05-07T23:26:31.31993Z","end":"2026-05-07T23:26:31.468511Z","steps":["trace[1678493554] 'process raft request' (duration: 148.29633ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:31.468652Z","caller":"traceutil/trace.go:171","msg":"trace[132962771] transaction","detail":"{read_only:false; response_revision:3788; number_of_response:1; }","duration":"146.773762ms","start":"2026-05-07T23:26:31.321869Z","end":"2026-05-07T23:26:31.468642Z","steps":["trace[132962771] 'process raft request' (duration: 146.422395ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:31.468739Z","caller":"traceutil/trace.go:171","msg":"trace[1431608010] transaction","detail":"{read_only:false; response_revision:3789; number_of_response:1; }","duration":"146.768331ms","start":"2026-05-07T23:26:31.321963Z","end":"2026-05-07T23:26:31.468732Z","steps":["trace[1431608010] 'process raft request' (duration: 146.358284ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:31.469891Z","caller":"traceutil/trace.go:171","msg":"trace[2055681410] transaction","detail":"{read_only:false; response_revision:3792; number_of_response:1; }","duration":"143.932918ms","start":"2026-05-07T23:26:31.325943Z","end":"2026-05-07T23:26:31.469875Z","steps":["trace[2055681410] 'process raft request' (duration: 142.478122ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:31.469933Z","caller":"traceutil/trace.go:171","msg":"trace[456721972] transaction","detail":"{read_only:false; response_revision:3791; number_of_response:1; }","duration":"147.20215ms","start":"2026-05-07T23:26:31.322698Z","end":"2026-05-07T23:26:31.469901Z","steps":["trace[456721972] 'process raft request' (duration: 145.690742ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:31.470082Z","caller":"traceutil/trace.go:171","msg":"trace[1680019771] transaction","detail":"{read_only:false; response_revision:3790; number_of_response:1; }","duration":"148.098377ms","start":"2026-05-07T23:26:31.321969Z","end":"2026-05-07T23:26:31.470067Z","steps":["trace[1680019771] 'process raft request' (duration: 146.377694ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:33.287703Z","caller":"traceutil/trace.go:171","msg":"trace[1654852577] transaction","detail":"{read_only:false; response_revision:3810; number_of_response:1; }","duration":"105.482403ms","start":"2026-05-07T23:26:33.182178Z","end":"2026-05-07T23:26:33.28766Z","steps":["trace[1654852577] 'process raft request' (duration: 20.818397ms)","trace[1654852577] 'compare' (duration: 84.508443ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:14.172541Z","caller":"traceutil/trace.go:171","msg":"trace[687671247] linearizableReadLoop","detail":"{readStateIndex:4252; appliedIndex:4251; }","duration":"119.549542ms","start":"2026-05-07T23:27:14.052969Z","end":"2026-05-07T23:27:14.172519Z","steps":["trace[687671247] 'read index received' (duration: 119.41737ms)","trace[687671247] 'applied index is now lower than readState.Index' (duration: 131.482µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:14.172565Z","caller":"traceutil/trace.go:171","msg":"trace[617467785] transaction","detail":"{read_only:false; response_revision:4091; number_of_response:1; }","duration":"146.398049ms","start":"2026-05-07T23:27:14.02614Z","end":"2026-05-07T23:27:14.172538Z","steps":["trace[617467785] 'process raft request' (duration: 146.228076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:14.172753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.761256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-07T23:27:14.172788Z","caller":"traceutil/trace.go:171","msg":"trace[2054479406] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4091; }","duration":"119.812807ms","start":"2026-05-07T23:27:14.052965Z","end":"2026-05-07T23:27:14.172778Z","steps":["trace[2054479406] 'agreement among raft nodes before linearized reading' (duration: 119.684075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:14.173338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.902388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:14.173454Z","caller":"traceutil/trace.go:171","msg":"trace[1060081488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4091; }","duration":"108.409057ms","start":"2026-05-07T23:27:14.065023Z","end":"2026-05-07T23:27:14.173432Z","steps":["trace[1060081488] 'agreement among raft nodes before linearized reading' (duration: 107.814497ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:24.547844Z","caller":"traceutil/trace.go:171","msg":"trace[909861415] transaction","detail":"{read_only:false; response_revision:4537; number_of_response:1; }","duration":"121.302515ms","start":"2026-05-07T23:27:24.426516Z","end":"2026-05-07T23:27:24.547818Z","steps":["trace[909861415] 'process raft request' (duration: 121.174713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:24.547946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.017139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:24.547995Z","caller":"traceutil/trace.go:171","msg":"trace[582513467] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:4537; }","duration":"121.08105ms","start":"2026-05-07T23:27:24.426903Z","end":"2026-05-07T23:27:24.547984Z","steps":["trace[582513467] 'agreement among raft nodes before linearized reading' (duration: 120.993668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:24.548148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.248322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:18 size:485548"} {"level":"info","ts":"2026-05-07T23:27:24.54819Z","caller":"traceutil/trace.go:171","msg":"trace[1632206612] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:18; response_revision:4537; }","duration":"100.320323ms","start":"2026-05-07T23:27:24.44786Z","end":"2026-05-07T23:27:24.54818Z","steps":["trace[1632206612] 'agreement among raft nodes before linearized reading' (duration: 99.961737ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:26.083002Z","caller":"traceutil/trace.go:171","msg":"trace[1166831083] linearizableReadLoop","detail":"{readStateIndex:4753; appliedIndex:4752; }","duration":"230.001504ms","start":"2026-05-07T23:27:25.852975Z","end":"2026-05-07T23:27:26.082977Z","steps":["trace[1166831083] 'read index received' (duration: 229.75897ms)","trace[1166831083] 'applied index is now lower than readState.Index' (duration: 241.654µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:26.083135Z","caller":"traceutil/trace.go:171","msg":"trace[1261630532] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"281.375187ms","start":"2026-05-07T23:27:25.801744Z","end":"2026-05-07T23:27:26.083119Z","steps":["trace[1261630532] 'process raft request' (duration: 281.073312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:26.083613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.620505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-07T23:27:26.083647Z","caller":"traceutil/trace.go:171","msg":"trace[1755014061] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4584; }","duration":"230.711446ms","start":"2026-05-07T23:27:25.852929Z","end":"2026-05-07T23:27:26.08364Z","steps":["trace[1755014061] 'agreement among raft nodes before linearized reading' (duration: 230.515762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:26.08385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.087042ms","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-05-07T23:27:26.083876Z","caller":"traceutil/trace.go:171","msg":"trace[778898316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4584; }","duration":"203.151175ms","start":"2026-05-07T23:27:25.88072Z","end":"2026-05-07T23:27:26.083871Z","steps":["trace[778898316] 'agreement among raft nodes before linearized reading' (duration: 203.076293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:26.084191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.450924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60c8581b-9f42-4bd6-9fd6-858d2dabfae2\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-05-07T23:27:26.084224Z","caller":"traceutil/trace.go:171","msg":"trace[1986696873] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60c8581b-9f42-4bd6-9fd6-858d2dabfae2; range_end:; response_count:1; response_revision:4584; }","duration":"131.503404ms","start":"2026-05-07T23:27:25.952711Z","end":"2026-05-07T23:27:26.084214Z","steps":["trace[1986696873] 'agreement among raft nodes before linearized reading' (duration: 131.429423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:26.084433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.383881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-60c8581b-9f42-4bd6-9fd6-858d2dabfae2\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-05-07T23:27:26.08451Z","caller":"traceutil/trace.go:171","msg":"trace[827197089] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-60c8581b-9f42-4bd6-9fd6-858d2dabfae2; range_end:; response_count:1; response_revision:4584; }","duration":"195.501783ms","start":"2026-05-07T23:27:25.888991Z","end":"2026-05-07T23:27:26.084493Z","steps":["trace[827197089] 'agreement among raft nodes before linearized reading' (duration: 195.283869ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:28.543475Z","caller":"traceutil/trace.go:171","msg":"trace[2123769519] transaction","detail":"{read_only:false; response_revision:4642; number_of_response:1; }","duration":"110.250494ms","start":"2026-05-07T23:27:28.433191Z","end":"2026-05-07T23:27:28.543442Z","steps":["trace[2123769519] 'process raft request' (duration: 110.001269ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:28.695758Z","caller":"traceutil/trace.go:171","msg":"trace[133477662] linearizableReadLoop","detail":"{readStateIndex:4816; appliedIndex:4815; }","duration":"108.193259ms","start":"2026-05-07T23:27:28.587543Z","end":"2026-05-07T23:27:28.695737Z","steps":["trace[133477662] 'read index received' (duration: 108.066487ms)","trace[133477662] 'applied index is now lower than readState.Index' (duration: 126.142µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:28.695903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.350521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aef44834-b74c-4bb8-a060-2d3ebfc81a1e\" ","response":"range_response_count:1 size:4080"} {"level":"info","ts":"2026-05-07T23:27:28.695927Z","caller":"traceutil/trace.go:171","msg":"trace[1861462475] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aef44834-b74c-4bb8-a060-2d3ebfc81a1e; range_end:; response_count:1; response_revision:4643; }","duration":"108.416142ms","start":"2026-05-07T23:27:28.587503Z","end":"2026-05-07T23:27:28.69592Z","steps":["trace[1861462475] 'agreement among raft nodes before linearized reading' (duration: 108.29828ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:28.696001Z","caller":"traceutil/trace.go:171","msg":"trace[925106529] transaction","detail":"{read_only:false; response_revision:4643; number_of_response:1; }","duration":"146.28991ms","start":"2026-05-07T23:27:28.549705Z","end":"2026-05-07T23:27:28.695995Z","steps":["trace[925106529] 'process raft request' (duration: 145.952255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:35.234203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.550626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:27:35.234359Z","caller":"traceutil/trace.go:171","msg":"trace[723816688] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"667.915453ms","start":"2026-05-07T23:27:34.566411Z","end":"2026-05-07T23:27:35.234326Z","steps":["trace[723816688] 'process raft request' (duration: 444.141863ms)","trace[723816688] 'compare' (duration: 223.228551ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:35.234301Z","caller":"traceutil/trace.go:171","msg":"trace[261631900] linearizableReadLoop","detail":"{readStateIndex:4954; appliedIndex:4953; }","duration":"657.924572ms","start":"2026-05-07T23:27:34.576361Z","end":"2026-05-07T23:27:35.234285Z","steps":["trace[261631900] 'read index received' (duration: 434.105022ms)","trace[261631900] 'applied index is now lower than readState.Index' (duration: 223.81837ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:35.234869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.483303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0dfd4f05-b431-4bc8-b2b1-6cbda5ed50ad\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-07T23:27:35.234976Z","caller":"traceutil/trace.go:171","msg":"trace[1700238691] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0dfd4f05-b431-4bc8-b2b1-6cbda5ed50ad; range_end:; response_count:1; response_revision:4778; }","duration":"658.631835ms","start":"2026-05-07T23:27:34.576325Z","end":"2026-05-07T23:27:35.234957Z","steps":["trace[1700238691] 'agreement among raft nodes before linearized reading' (duration: 658.351231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:35.235056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:34.576309Z","time spent":"658.734227ms","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0dfd4f05-b431-4bc8-b2b1-6cbda5ed50ad\" "} {"level":"warn","ts":"2026-05-07T23:27:35.235221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:34.566387Z","time spent":"668.255069ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:35.234972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.478071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:35.235583Z","caller":"traceutil/trace.go:171","msg":"trace[980039042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4778; }","duration":"170.125432ms","start":"2026-05-07T23:27:35.065446Z","end":"2026-05-07T23:27:35.235571Z","steps":["trace[980039042] 'agreement among raft nodes before linearized reading' (duration: 169.462171ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:35.540121Z","caller":"traceutil/trace.go:171","msg":"trace[882081384] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"181.141458ms","start":"2026-05-07T23:27:35.35895Z","end":"2026-05-07T23:27:35.540092Z","steps":["trace[882081384] 'process raft request' (duration: 111.915715ms)","trace[882081384] 'compare' (duration: 69.102961ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:35.556423Z","caller":"traceutil/trace.go:171","msg":"trace[687755211] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4954; }","duration":"186.851275ms","start":"2026-05-07T23:27:35.369547Z","end":"2026-05-07T23:27:35.556399Z","steps":["trace[687755211] 'read index received' (duration: 101.302316ms)","trace[687755211] 'applied index is now lower than readState.Index' (duration: 85.547729ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:35.556614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.052498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:35.556673Z","caller":"traceutil/trace.go:171","msg":"trace[907511514] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:4780; }","duration":"187.16485ms","start":"2026-05-07T23:27:35.369495Z","end":"2026-05-07T23:27:35.556659Z","steps":["trace[907511514] 'agreement among raft nodes before linearized reading' (duration: 187.051507ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:35.556736Z","caller":"traceutil/trace.go:171","msg":"trace[1577294387] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"192.662933ms","start":"2026-05-07T23:27:35.363866Z","end":"2026-05-07T23:27:35.556529Z","steps":["trace[1577294387] 'process raft request' (duration: 192.425059ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:35.83772Z","caller":"traceutil/trace.go:171","msg":"trace[1701908159] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"189.574402ms","start":"2026-05-07T23:27:35.64809Z","end":"2026-05-07T23:27:35.837664Z","steps":["trace[1701908159] 'process raft request' (duration: 160.793864ms)","trace[1701908159] 'compare' (duration: 28.411802ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:36.408301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.951924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:27:36.408778Z","caller":"traceutil/trace.go:171","msg":"trace[342294704] linearizableReadLoop","detail":"{readStateIndex:4967; appliedIndex:4960; }","duration":"517.921714ms","start":"2026-05-07T23:27:35.890839Z","end":"2026-05-07T23:27:36.40876Z","steps":["trace[342294704] 'read index received' (duration: 8.799199ms)","trace[342294704] 'applied index is now lower than readState.Index' (duration: 509.121725ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:36.409013Z","caller":"traceutil/trace.go:171","msg":"trace[36958929] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"564.436213ms","start":"2026-05-07T23:27:35.844564Z","end":"2026-05-07T23:27:36.409Z","steps":["trace[36958929] 'process raft request' (duration: 563.953255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.40909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:35.84454Z","time spent":"564.510834ms","remote":"127.0.0.1:34690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:36.409209Z","caller":"traceutil/trace.go:171","msg":"trace[1092114142] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"566.367795ms","start":"2026-05-07T23:27:35.842807Z","end":"2026-05-07T23:27:36.409175Z","steps":["trace[1092114142] 'process raft request' (duration: 135.439644ms)","trace[1092114142] 'compare' (duration: 429.384104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:36.40943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.582046ms","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":"warn","ts":"2026-05-07T23:27:36.409436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:35.842789Z","time spent":"566.542977ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:36.409481Z","caller":"traceutil/trace.go:171","msg":"trace[524783881] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4791; }","duration":"518.665677ms","start":"2026-05-07T23:27:35.890802Z","end":"2026-05-07T23:27:36.409468Z","steps":["trace[524783881] 'agreement among raft nodes before linearized reading' (duration: 518.580715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.409517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:35.890784Z","time spent":"518.724147ms","remote":"127.0.0.1:60306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-07T23:27:36.409407Z","caller":"traceutil/trace.go:171","msg":"trace[765241751] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"557.467874ms","start":"2026-05-07T23:27:35.851892Z","end":"2026-05-07T23:27:36.40936Z","steps":["trace[765241751] 'process raft request' (duration: 556.817533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.409704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.86957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:36.40963Z","caller":"traceutil/trace.go:171","msg":"trace[437511381] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"565.451019ms","start":"2026-05-07T23:27:35.844108Z","end":"2026-05-07T23:27:36.409559Z","steps":["trace[437511381] 'process raft request' (duration: 564.30687ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:36.409746Z","caller":"traceutil/trace.go:171","msg":"trace[1195104507] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:4791; }","duration":"355.957922ms","start":"2026-05-07T23:27:36.053778Z","end":"2026-05-07T23:27:36.409736Z","steps":["trace[1195104507] 'agreement among raft nodes before linearized reading' (duration: 355.896621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.409777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:36.053754Z","time spent":"356.015353ms","remote":"127.0.0.1:33822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"info","ts":"2026-05-07T23:27:36.409838Z","caller":"traceutil/trace.go:171","msg":"trace[1049684134] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"564.739847ms","start":"2026-05-07T23:27:35.845073Z","end":"2026-05-07T23:27:36.409813Z","steps":["trace[1049684134] 'process raft request' (duration: 563.581178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.409933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:35.84409Z","time spent":"565.663603ms","remote":"127.0.0.1:34584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:36.410034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:35.845057Z","time spent":"564.87874ms","remote":"127.0.0.1:34584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1272,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:36.410289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.682974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0dfd4f05-b431-4bc8-b2b1-6cbda5ed50ad\" ","response":"range_response_count:1 size:3766"} {"level":"warn","ts":"2026-05-07T23:27:36.409874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:35.851884Z","time spent":"557.781939ms","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:36.41058Z","caller":"traceutil/trace.go:171","msg":"trace[1186960099] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0dfd4f05-b431-4bc8-b2b1-6cbda5ed50ad; range_end:; response_count:1; response_revision:4791; }","duration":"171.825197ms","start":"2026-05-07T23:27:36.23851Z","end":"2026-05-07T23:27:36.410336Z","steps":["trace[1186960099] 'agreement among raft nodes before linearized reading' (duration: 171.712485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.410698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.382998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:36.410762Z","caller":"traceutil/trace.go:171","msg":"trace[923327847] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4791; }","duration":"269.481599ms","start":"2026-05-07T23:27:36.141261Z","end":"2026-05-07T23:27:36.410743Z","steps":["trace[923327847] 'agreement among raft nodes before linearized reading' (duration: 269.395127ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:36.410297Z","caller":"traceutil/trace.go:171","msg":"trace[1921915090] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"565.210295ms","start":"2026-05-07T23:27:35.845031Z","end":"2026-05-07T23:27:36.410241Z","steps":["trace[1921915090] 'process raft request' (duration: 563.542918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.410852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:35.84501Z","time spent":"565.798735ms","remote":"127.0.0.1:34690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1392,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:36.410864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.982346ms","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-05-07T23:27:36.41096Z","caller":"traceutil/trace.go:171","msg":"trace[29648266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4791; }","duration":"199.105088ms","start":"2026-05-07T23:27:36.2118Z","end":"2026-05-07T23:27:36.410905Z","steps":["trace[29648266] 'agreement among raft nodes before linearized reading' (duration: 198.878205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.410986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.887438ms","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-05-07T23:27:36.411017Z","caller":"traceutil/trace.go:171","msg":"trace[355061876] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4791; }","duration":"252.950259ms","start":"2026-05-07T23:27:36.158057Z","end":"2026-05-07T23:27:36.411007Z","steps":["trace[355061876] 'agreement among raft nodes before linearized reading' (duration: 252.860628ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:36.870934Z","caller":"traceutil/trace.go:171","msg":"trace[951633569] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"451.951637ms","start":"2026-05-07T23:27:36.418937Z","end":"2026-05-07T23:27:36.870889Z","steps":["trace[951633569] 'process raft request' (duration: 363.988479ms)","trace[951633569] 'compare' (duration: 87.681214ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:36.87136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:36.418912Z","time spent":"452.12045ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:36.885533Z","caller":"traceutil/trace.go:171","msg":"trace[1095546418] transaction","detail":"{read_only:false; response_revision:4793; number_of_response:1; }","duration":"466.480133ms","start":"2026-05-07T23:27:36.419027Z","end":"2026-05-07T23:27:36.885507Z","steps":["trace[1095546418] 'process raft request' (duration: 466.31696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.88567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:36.419009Z","time spent":"466.591754ms","remote":"127.0.0.1:34690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:36.88603Z","caller":"traceutil/trace.go:171","msg":"trace[1591894658] transaction","detail":"{read_only:false; response_revision:4794; number_of_response:1; }","duration":"464.881685ms","start":"2026-05-07T23:27:36.421136Z","end":"2026-05-07T23:27:36.886017Z","steps":["trace[1591894658] 'process raft request' (duration: 464.323286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.886078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:36.421119Z","time spent":"464.933026ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:36.886722Z","caller":"traceutil/trace.go:171","msg":"trace[593658804] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"465.552676ms","start":"2026-05-07T23:27:36.421145Z","end":"2026-05-07T23:27:36.886697Z","steps":["trace[593658804] 'process raft request' (duration: 464.686572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.886877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:36.421133Z","time spent":"465.653598ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:36.887488Z","caller":"traceutil/trace.go:171","msg":"trace[1986441739] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"466.32065ms","start":"2026-05-07T23:27:36.421144Z","end":"2026-05-07T23:27:36.887465Z","steps":["trace[1986441739] 'process raft request' (duration: 466.042804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.887558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:36.421125Z","time spent":"466.403181ms","remote":"127.0.0.1:34690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:36.887544Z","caller":"traceutil/trace.go:171","msg":"trace[276384433] linearizableReadLoop","detail":"{readStateIndex:4975; appliedIndex:4967; }","duration":"337.878397ms","start":"2026-05-07T23:27:36.549643Z","end":"2026-05-07T23:27:36.887521Z","steps":["trace[276384433] 'read index received' (duration: 233.231776ms)","trace[276384433] 'applied index is now lower than readState.Index' (duration: 104.645041ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:36.887818Z","caller":"traceutil/trace.go:171","msg":"trace[925834277] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"466.660166ms","start":"2026-05-07T23:27:36.421149Z","end":"2026-05-07T23:27:36.887809Z","steps":["trace[925834277] 'process raft request' (duration: 466.183798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.887897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:36.421139Z","time spent":"466.701056ms","remote":"127.0.0.1:34584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:36.887915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.277313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-07T23:27:36.887946Z","caller":"traceutil/trace.go:171","msg":"trace[1079438024] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4799; }","duration":"338.345105ms","start":"2026-05-07T23:27:36.549589Z","end":"2026-05-07T23:27:36.887934Z","steps":["trace[1079438024] 'agreement among raft nodes before linearized reading' (duration: 338.230983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.887968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:36.54957Z","time spent":"338.392535ms","remote":"127.0.0.1:34570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-05-07T23:27:36.888045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.067451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:36.888074Z","caller":"traceutil/trace.go:171","msg":"trace[1543310990] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:4799; }","duration":"127.139582ms","start":"2026-05-07T23:27:36.760928Z","end":"2026-05-07T23:27:36.888067Z","steps":["trace[1543310990] 'agreement among raft nodes before linearized reading' (duration: 127.093471ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:36.888133Z","caller":"traceutil/trace.go:171","msg":"trace[1295324348] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"466.839929ms","start":"2026-05-07T23:27:36.421285Z","end":"2026-05-07T23:27:36.888125Z","steps":["trace[1295324348] 'process raft request' (duration: 466.126227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.888182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:36.421256Z","time spent":"466.90237ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:36.888278Z","caller":"traceutil/trace.go:171","msg":"trace[884682393] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"466.95176ms","start":"2026-05-07T23:27:36.421319Z","end":"2026-05-07T23:27:36.888271Z","steps":["trace[884682393] 'process raft request' (duration: 466.154517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:36.88833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:36.421286Z","time spent":"467.018752ms","remote":"127.0.0.1:34584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:37.156563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.897623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:27:37.156676Z","caller":"traceutil/trace.go:171","msg":"trace[1398258191] linearizableReadLoop","detail":"{readStateIndex:4977; appliedIndex:4976; }","duration":"249.977796ms","start":"2026-05-07T23:27:36.906685Z","end":"2026-05-07T23:27:37.156663Z","steps":["trace[1398258191] 'read index received' (duration: 87.536071ms)","trace[1398258191] 'applied index is now lower than readState.Index' (duration: 162.440915ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:37.156715Z","caller":"traceutil/trace.go:171","msg":"trace[2013816815] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"252.913377ms","start":"2026-05-07T23:27:36.903759Z","end":"2026-05-07T23:27:37.156672Z","steps":["trace[2013816815] 'process raft request' (duration: 89.83726ms)","trace[2013816815] 'compare' (duration: 162.758641ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:27:37.156874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.18335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-07T23:27:37.156948Z","caller":"traceutil/trace.go:171","msg":"trace[252547163] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4800; }","duration":"250.234441ms","start":"2026-05-07T23:27:36.906662Z","end":"2026-05-07T23:27:37.156897Z","steps":["trace[252547163] 'agreement among raft nodes before linearized reading' (duration: 250.071009ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:38.70491Z","caller":"traceutil/trace.go:171","msg":"trace[1530818251] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"134.911637ms","start":"2026-05-07T23:27:38.569974Z","end":"2026-05-07T23:27:38.704886Z","steps":["trace[1530818251] 'process raft request' (duration: 134.561551ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:40.047323Z","caller":"traceutil/trace.go:171","msg":"trace[1315115994] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"127.860015ms","start":"2026-05-07T23:27:39.919438Z","end":"2026-05-07T23:27:40.047298Z","steps":["trace[1315115994] 'process raft request' (duration: 127.677701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:41.808129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.418119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:27:41.808518Z","caller":"traceutil/trace.go:171","msg":"trace[1271376366] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"234.103996ms","start":"2026-05-07T23:27:41.57439Z","end":"2026-05-07T23:27:41.808494Z","steps":["trace[1271376366] 'process raft request' (duration: 23.248851ms)","trace[1271376366] 'compare' (duration: 210.020631ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:45.690936Z","caller":"traceutil/trace.go:171","msg":"trace[323391553] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"101.128103ms","start":"2026-05-07T23:27:45.589785Z","end":"2026-05-07T23:27:45.690914Z","steps":["trace[323391553] 'process raft request' (duration: 101.005051ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:45.858752Z","caller":"traceutil/trace.go:171","msg":"trace[68682912] linearizableReadLoop","detail":"{readStateIndex:5097; appliedIndex:5095; }","duration":"227.458107ms","start":"2026-05-07T23:27:45.631264Z","end":"2026-05-07T23:27:45.858722Z","steps":["trace[68682912] 'read index received' (duration: 59.598057ms)","trace[68682912] 'applied index is now lower than readState.Index' (duration: 167.85946ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:45.858928Z","caller":"traceutil/trace.go:171","msg":"trace[1503526663] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"264.966524ms","start":"2026-05-07T23:27:45.593953Z","end":"2026-05-07T23:27:45.85892Z","steps":["trace[1503526663] 'process raft request' (duration: 264.668739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:45.858975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.704901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:27:45.859002Z","caller":"traceutil/trace.go:171","msg":"trace[1204796065] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:4915; }","duration":"227.746572ms","start":"2026-05-07T23:27:45.631249Z","end":"2026-05-07T23:27:45.858995Z","steps":["trace[1204796065] 'agreement among raft nodes before linearized reading' (duration: 227.686161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:45.859764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.88084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-07T23:27:45.859809Z","caller":"traceutil/trace.go:171","msg":"trace[300087016] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4916; }","duration":"179.928041ms","start":"2026-05-07T23:27:45.67987Z","end":"2026-05-07T23:27:45.859798Z","steps":["trace[300087016] 'agreement among raft nodes before linearized reading' (duration: 179.789869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:50.126887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11858714512002104992,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T23:27:50.282589Z","caller":"traceutil/trace.go:171","msg":"trace[1406930567] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"656.557383ms","start":"2026-05-07T23:27:49.626006Z","end":"2026-05-07T23:27:50.282563Z","steps":["trace[1406930567] 'process raft request' (duration: 656.38473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:50.282883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:49.625994Z","time spent":"656.673686ms","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:27:50.292586Z","caller":"traceutil/trace.go:171","msg":"trace[1156740617] linearizableReadLoop","detail":"{readStateIndex:5150; appliedIndex:5147; }","duration":"666.244845ms","start":"2026-05-07T23:27:49.626328Z","end":"2026-05-07T23:27:50.292573Z","steps":["trace[1156740617] 'read index received' (duration: 656.099956ms)","trace[1156740617] 'applied index is now lower than readState.Index' (duration: 10.144149ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:50.292656Z","caller":"traceutil/trace.go:171","msg":"trace[775633209] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"417.294778ms","start":"2026-05-07T23:27:49.875354Z","end":"2026-05-07T23:27:50.292648Z","steps":["trace[775633209] 'process raft request' (duration: 417.193196ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:50.292651Z","caller":"traceutil/trace.go:171","msg":"trace[1959238168] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"588.233755ms","start":"2026-05-07T23:27:49.704392Z","end":"2026-05-07T23:27:50.292626Z","steps":["trace[1959238168] 'process raft request' (duration: 588.069773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:50.29272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:49.875329Z","time spent":"417.341358ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:50.292772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:49.70436Z","time spent":"588.344418ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:27:50.292953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.635272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0dfd4f05-b431-4bc8-b2b1-6cbda5ed50ad\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-07T23:27:50.292976Z","caller":"traceutil/trace.go:171","msg":"trace[247593973] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0dfd4f05-b431-4bc8-b2b1-6cbda5ed50ad; range_end:; response_count:1; response_revision:4967; }","duration":"666.689463ms","start":"2026-05-07T23:27:49.626279Z","end":"2026-05-07T23:27:50.292969Z","steps":["trace[247593973] 'agreement among raft nodes before linearized reading' (duration: 666.570881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:50.292995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:49.626263Z","time spent":"666.727513ms","remote":"127.0.0.1:34554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-0dfd4f05-b431-4bc8-b2b1-6cbda5ed50ad\" "} {"level":"info","ts":"2026-05-07T23:27:50.441047Z","caller":"traceutil/trace.go:171","msg":"trace[1363288784] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"150.438784ms","start":"2026-05-07T23:27:50.290582Z","end":"2026-05-07T23:27:50.441021Z","steps":["trace[1363288784] 'process raft request' (duration: 83.978568ms)","trace[1363288784] 'compare' (duration: 66.274873ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:50.441469Z","caller":"traceutil/trace.go:171","msg":"trace[561998818] linearizableReadLoop","detail":"{readStateIndex:5153; appliedIndex:5150; }","duration":"148.817207ms","start":"2026-05-07T23:27:50.292628Z","end":"2026-05-07T23:27:50.441445Z","steps":["trace[561998818] 'read index received' (duration: 81.947984ms)","trace[561998818] 'applied index is now lower than readState.Index' (duration: 66.868223ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:27:50.441575Z","caller":"traceutil/trace.go:171","msg":"trace[1269319368] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"150.618927ms","start":"2026-05-07T23:27:50.290945Z","end":"2026-05-07T23:27:50.441564Z","steps":["trace[1269319368] 'process raft request' (duration: 150.374923ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:50.44156Z","caller":"traceutil/trace.go:171","msg":"trace[82548464] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"150.636667ms","start":"2026-05-07T23:27:50.290896Z","end":"2026-05-07T23:27:50.441533Z","steps":["trace[82548464] 'process raft request' (duration: 150.074688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:50.441872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.065884ms","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-05-07T23:27:50.441914Z","caller":"traceutil/trace.go:171","msg":"trace[1435561612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4970; }","duration":"336.158254ms","start":"2026-05-07T23:27:50.105746Z","end":"2026-05-07T23:27:50.441904Z","steps":["trace[1435561612] 'agreement among raft nodes before linearized reading' (duration: 336.009892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:50.441983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:50.105725Z","time spent":"336.246707ms","remote":"127.0.0.1:34674","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-05-07T23:27:50.442442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.251254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-07T23:27:50.442499Z","caller":"traceutil/trace.go:171","msg":"trace[1870961974] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4970; }","duration":"429.349245ms","start":"2026-05-07T23:27:50.013138Z","end":"2026-05-07T23:27:50.442487Z","steps":["trace[1870961974] 'agreement among raft nodes before linearized reading' (duration: 428.850407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:50.442534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:27:50.013118Z","time spent":"429.406827ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":500,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-07T23:27:50.474541Z","caller":"traceutil/trace.go:171","msg":"trace[2009648150] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"181.124035ms","start":"2026-05-07T23:27:50.293394Z","end":"2026-05-07T23:27:50.474518Z","steps":["trace[2009648150] 'process raft request' (duration: 180.965352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:27:50.474739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.591882ms","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":"info","ts":"2026-05-07T23:27:50.474827Z","caller":"traceutil/trace.go:171","msg":"trace[1003961998] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:4971; }","duration":"107.735273ms","start":"2026-05-07T23:27:50.367077Z","end":"2026-05-07T23:27:50.474812Z","steps":["trace[1003961998] 'agreement among raft nodes before linearized reading' (duration: 107.416088ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:29.505392Z","caller":"traceutil/trace.go:171","msg":"trace[63375765] linearizableReadLoop","detail":"{readStateIndex:5492; appliedIndex:5490; }","duration":"199.468395ms","start":"2026-05-07T23:28:29.305906Z","end":"2026-05-07T23:28:29.505374Z","steps":["trace[63375765] 'read index received' (duration: 199.289882ms)","trace[63375765] 'applied index is now lower than readState.Index' (duration: 177.823µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:28:29.505453Z","caller":"traceutil/trace.go:171","msg":"trace[615229817] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"243.534341ms","start":"2026-05-07T23:28:29.261876Z","end":"2026-05-07T23:28:29.50541Z","steps":["trace[615229817] 'process raft request' (duration: 243.340008ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:29.505462Z","caller":"traceutil/trace.go:171","msg":"trace[1693948231] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"242.040547ms","start":"2026-05-07T23:28:29.263392Z","end":"2026-05-07T23:28:29.505432Z","steps":["trace[1693948231] 'process raft request' (duration: 241.953346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:28:29.505511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.592578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:28:29.505538Z","caller":"traceutil/trace.go:171","msg":"trace[924148297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5300; }","duration":"199.641189ms","start":"2026-05-07T23:28:29.305888Z","end":"2026-05-07T23:28:29.505529Z","steps":["trace[924148297] 'agreement among raft nodes before linearized reading' (duration: 199.576248ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:42.982759Z","caller":"traceutil/trace.go:171","msg":"trace[856576150] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"104.464556ms","start":"2026-05-07T23:28:42.878266Z","end":"2026-05-07T23:28:42.98273Z","steps":["trace[856576150] 'process raft request' (duration: 104.230032ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:28:53.841438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2850} {"level":"info","ts":"2026-05-07T23:28:53.942524Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2850,"took":"99.521584ms","hash":1101002649,"current-db-size-bytes":53186560,"current-db-size":"53 MB","current-db-size-in-use-bytes":28561408,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-07T23:28:53.942617Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1101002649,"revision":2850,"compact-revision":-1} {"level":"info","ts":"2026-05-07T23:29:37.036167Z","caller":"traceutil/trace.go:171","msg":"trace[1529375203] transaction","detail":"{read_only:false; response_revision:5783; number_of_response:1; }","duration":"136.212774ms","start":"2026-05-07T23:29:36.899925Z","end":"2026-05-07T23:29:37.036138Z","steps":["trace[1529375203] 'process raft request' (duration: 98.605709ms)","trace[1529375203] 'compare' (duration: 37.482983ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:29:43.871394Z","caller":"traceutil/trace.go:171","msg":"trace[1468702481] linearizableReadLoop","detail":"{readStateIndex:6025; appliedIndex:6024; }","duration":"371.124368ms","start":"2026-05-07T23:29:43.500241Z","end":"2026-05-07T23:29:43.871366Z","steps":["trace[1468702481] 'read index received' (duration: 369.658126ms)","trace[1468702481] 'applied index is now lower than readState.Index' (duration: 1.465082ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:29:43.871836Z","caller":"traceutil/trace.go:171","msg":"trace[1970488871] transaction","detail":"{read_only:false; response_revision:5816; number_of_response:1; }","duration":"469.774662ms","start":"2026-05-07T23:29:43.402042Z","end":"2026-05-07T23:29:43.871817Z","steps":["trace[1970488871] 'process raft request' (duration: 467.969445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:29:43.872041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.660128ms","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-05-07T23:29:43.87211Z","caller":"traceutil/trace.go:171","msg":"trace[323864592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5817; }","duration":"213.781789ms","start":"2026-05-07T23:29:43.658313Z","end":"2026-05-07T23:29:43.872095Z","steps":["trace[323864592] 'agreement among raft nodes before linearized reading' (duration: 213.599786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:29:43.872252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:29:43.402026Z","time spent":"469.862394ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:29:43.87259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.328486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-07T23:29:43.872705Z","caller":"traceutil/trace.go:171","msg":"trace[2024035931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5817; }","duration":"372.492029ms","start":"2026-05-07T23:29:43.500195Z","end":"2026-05-07T23:29:43.872687Z","steps":["trace[2024035931] 'agreement among raft nodes before linearized reading' (duration: 371.797607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:29:43.872763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:29:43.500174Z","time spent":"372.579441ms","remote":"127.0.0.1:34674","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-05-07T23:30:05.618682Z","caller":"traceutil/trace.go:171","msg":"trace[51090250] transaction","detail":"{read_only:false; response_revision:5926; number_of_response:1; }","duration":"126.362987ms","start":"2026-05-07T23:30:05.492257Z","end":"2026-05-07T23:30:05.61862Z","steps":["trace[51090250] 'process raft request' (duration: 126.027332ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:06.453002Z","caller":"traceutil/trace.go:171","msg":"trace[1465214262] linearizableReadLoop","detail":"{readStateIndex:6140; appliedIndex:6139; }","duration":"482.235305ms","start":"2026-05-07T23:30:05.970712Z","end":"2026-05-07T23:30:06.452947Z","steps":["trace[1465214262] 'read index received' (duration: 482.043252ms)","trace[1465214262] 'applied index is now lower than readState.Index' (duration: 191.023µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:06.45301Z","caller":"traceutil/trace.go:171","msg":"trace[1690508196] transaction","detail":"{read_only:false; response_revision:5927; number_of_response:1; }","duration":"508.711114ms","start":"2026-05-07T23:30:05.94427Z","end":"2026-05-07T23:30:06.452981Z","steps":["trace[1690508196] 'process raft request' (duration: 508.471451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:06.453274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.558809ms","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-05-07T23:30:06.453329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.967065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-07T23:30:06.453367Z","caller":"traceutil/trace.go:171","msg":"trace[2035374735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5927; }","duration":"479.062647ms","start":"2026-05-07T23:30:05.974296Z","end":"2026-05-07T23:30:06.453358Z","steps":["trace[2035374735] 'agreement among raft nodes before linearized reading' (duration: 478.886854ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:06.453359Z","caller":"traceutil/trace.go:171","msg":"trace[2095685240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5927; }","duration":"482.725862ms","start":"2026-05-07T23:30:05.970613Z","end":"2026-05-07T23:30:06.453339Z","steps":["trace[2095685240] 'agreement among raft nodes before linearized reading' (duration: 482.456908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:06.453397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:05.974274Z","time spent":"479.113177ms","remote":"127.0.0.1:34674","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-05-07T23:30:06.453403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:05.970575Z","time spent":"482.818733ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T23:30:06.453497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.444947ms","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-05-07T23:30:06.453543Z","caller":"traceutil/trace.go:171","msg":"trace[393970866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5927; }","duration":"358.580729ms","start":"2026-05-07T23:30:06.094952Z","end":"2026-05-07T23:30:06.453533Z","steps":["trace[393970866] 'agreement among raft nodes before linearized reading' (duration: 358.413637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:06.453562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.052731ms","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-05-07T23:30:06.453576Z","caller":"traceutil/trace.go:171","msg":"trace[1017967806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5927; }","duration":"478.084571ms","start":"2026-05-07T23:30:05.975487Z","end":"2026-05-07T23:30:06.453572Z","steps":["trace[1017967806] 'agreement among raft nodes before linearized reading' (duration: 478.04067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:06.453595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:05.975478Z","time spent":"478.113681ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T23:30:06.45359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:06.094885Z","time spent":"358.682091ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T23:30:06.453303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:05.944231Z","time spent":"508.944068ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:30:06.453519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.687648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:30:06.453712Z","caller":"traceutil/trace.go:171","msg":"trace[162541744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5927; }","duration":"387.919691ms","start":"2026-05-07T23:30:06.065783Z","end":"2026-05-07T23:30:06.453703Z","steps":["trace[162541744] 'agreement among raft nodes before linearized reading' (duration: 387.718248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:06.453705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.031459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-05-07T23:30:06.453748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:06.065763Z","time spent":"387.969251ms","remote":"127.0.0.1:34428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T23:30:06.453771Z","caller":"traceutil/trace.go:171","msg":"trace[2138482804] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5927; }","duration":"283.09021ms","start":"2026-05-07T23:30:06.170669Z","end":"2026-05-07T23:30:06.45376Z","steps":["trace[2138482804] 'agreement among raft nodes before linearized reading' (duration: 282.998668ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:06.684026Z","caller":"traceutil/trace.go:171","msg":"trace[577229447] transaction","detail":"{read_only:false; response_revision:5928; number_of_response:1; }","duration":"225.580863ms","start":"2026-05-07T23:30:06.458416Z","end":"2026-05-07T23:30:06.683997Z","steps":["trace[577229447] 'process raft request' (duration: 129.811378ms)","trace[577229447] 'compare' (duration: 95.537251ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:06.706809Z","caller":"traceutil/trace.go:171","msg":"trace[1940056609] transaction","detail":"{read_only:false; response_revision:5930; number_of_response:1; }","duration":"247.631336ms","start":"2026-05-07T23:30:06.459161Z","end":"2026-05-07T23:30:06.706792Z","steps":["trace[1940056609] 'process raft request' (duration: 247.548545ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:06.706837Z","caller":"traceutil/trace.go:171","msg":"trace[382310180] transaction","detail":"{read_only:false; response_revision:5929; number_of_response:1; }","duration":"247.958891ms","start":"2026-05-07T23:30:06.458844Z","end":"2026-05-07T23:30:06.706803Z","steps":["trace[382310180] 'process raft request' (duration: 247.767348ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:06.707133Z","caller":"traceutil/trace.go:171","msg":"trace[1743170080] transaction","detail":"{read_only:false; response_revision:5931; number_of_response:1; }","duration":"247.86112ms","start":"2026-05-07T23:30:06.459253Z","end":"2026-05-07T23:30:06.707114Z","steps":["trace[1743170080] 'process raft request' (duration: 247.498644ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:06.707214Z","caller":"traceutil/trace.go:171","msg":"trace[36771028] transaction","detail":"{read_only:false; response_revision:5932; number_of_response:1; }","duration":"247.701367ms","start":"2026-05-07T23:30:06.459495Z","end":"2026-05-07T23:30:06.707197Z","steps":["trace[36771028] 'process raft request' (duration: 247.509024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:09.847847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.929561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-05-07T23:30:09.847968Z","caller":"traceutil/trace.go:171","msg":"trace[2118486102] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5945; }","duration":"107.130714ms","start":"2026-05-07T23:30:09.740807Z","end":"2026-05-07T23:30:09.847937Z","steps":["trace[2118486102] 'range keys from in-memory index tree' (duration: 106.685867ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:14.929053Z","caller":"traceutil/trace.go:171","msg":"trace[1559652201] linearizableReadLoop","detail":"{readStateIndex:6185; appliedIndex:6184; }","duration":"146.918299ms","start":"2026-05-07T23:30:14.782106Z","end":"2026-05-07T23:30:14.929025Z","steps":["trace[1559652201] 'read index received' (duration: 139.944144ms)","trace[1559652201] 'applied index is now lower than readState.Index' (duration: 6.972725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:30:14.929307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.169702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:30:14.92935Z","caller":"traceutil/trace.go:171","msg":"trace[1311043419] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:5970; }","duration":"147.270874ms","start":"2026-05-07T23:30:14.782068Z","end":"2026-05-07T23:30:14.929339Z","steps":["trace[1311043419] 'agreement among raft nodes before linearized reading' (duration: 147.104482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:14.929678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.279178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-05-07T23:30:14.92968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.500607ms","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-05-07T23:30:14.929765Z","caller":"traceutil/trace.go:171","msg":"trace[1453197380] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5970; }","duration":"124.4019ms","start":"2026-05-07T23:30:14.805342Z","end":"2026-05-07T23:30:14.929744Z","steps":["trace[1453197380] 'agreement among raft nodes before linearized reading' (duration: 124.128236ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:14.929783Z","caller":"traceutil/trace.go:171","msg":"trace[275330158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5970; }","duration":"125.633329ms","start":"2026-05-07T23:30:14.804126Z","end":"2026-05-07T23:30:14.92976Z","steps":["trace[275330158] 'agreement among raft nodes before linearized reading' (duration: 125.338464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:14.929824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.635809ms","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-05-07T23:30:14.92987Z","caller":"traceutil/trace.go:171","msg":"trace[1806166303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5970; }","duration":"125.69652ms","start":"2026-05-07T23:30:14.804163Z","end":"2026-05-07T23:30:14.92986Z","steps":["trace[1806166303] 'agreement among raft nodes before linearized reading' (duration: 125.547558ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:14.929204Z","caller":"traceutil/trace.go:171","msg":"trace[1660485166] transaction","detail":"{read_only:false; response_revision:5970; number_of_response:1; }","duration":"173.425327ms","start":"2026-05-07T23:30:14.755754Z","end":"2026-05-07T23:30:14.929179Z","steps":["trace[1660485166] 'process raft request' (duration: 166.365651ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:15.147Z","caller":"traceutil/trace.go:171","msg":"trace[1259696183] transaction","detail":"{read_only:false; response_revision:5971; number_of_response:1; }","duration":"211.870335ms","start":"2026-05-07T23:30:14.935102Z","end":"2026-05-07T23:30:15.146972Z","steps":["trace[1259696183] 'process raft request' (duration: 189.245684ms)","trace[1259696183] 'compare' (duration: 22.312806ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:15.411919Z","caller":"traceutil/trace.go:171","msg":"trace[224986579] transaction","detail":"{read_only:false; response_revision:5972; number_of_response:1; }","duration":"476.768635ms","start":"2026-05-07T23:30:14.935128Z","end":"2026-05-07T23:30:15.411896Z","steps":["trace[224986579] 'process raft request' (duration: 383.96578ms)","trace[224986579] 'compare' (duration: 92.649453ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:30:15.412079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:14.935102Z","time spent":"476.893858ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:30:15.4122Z","caller":"traceutil/trace.go:171","msg":"trace[1628952245] transaction","detail":"{read_only:false; response_revision:5973; number_of_response:1; }","duration":"477.045479ms","start":"2026-05-07T23:30:14.935134Z","end":"2026-05-07T23:30:15.41218Z","steps":["trace[1628952245] 'process raft request' (duration: 476.727815ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:15.412315Z","caller":"traceutil/trace.go:171","msg":"trace[1840193693] transaction","detail":"{read_only:false; response_revision:5974; number_of_response:1; }","duration":"410.232715ms","start":"2026-05-07T23:30:15.002073Z","end":"2026-05-07T23:30:15.412306Z","steps":["trace[1840193693] 'process raft request' (duration: 410.031152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:15.412328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:14.935112Z","time spent":"477.146122ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:30:15.412374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:15.002057Z","time spent":"410.290276ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:30:15.412649Z","caller":"traceutil/trace.go:171","msg":"trace[1425048889] transaction","detail":"{read_only:false; response_revision:5975; number_of_response:1; }","duration":"395.301661ms","start":"2026-05-07T23:30:15.017337Z","end":"2026-05-07T23:30:15.412639Z","steps":["trace[1425048889] 'process raft request' (duration: 394.933736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:15.412735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:15.017323Z","time spent":"395.381362ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:30:39.896569Z","caller":"traceutil/trace.go:171","msg":"trace[266953599] transaction","detail":"{read_only:false; response_revision:6109; number_of_response:1; }","duration":"106.553857ms","start":"2026-05-07T23:30:39.789984Z","end":"2026-05-07T23:30:39.896538Z","steps":["trace[266953599] 'process raft request' (duration: 106.354674ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:33:53.847011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5484} {"level":"info","ts":"2026-05-07T23:33:53.933984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5484,"took":"84.103242ms","hash":4234023589,"current-db-size-bytes":53186560,"current-db-size":"53 MB","current-db-size-in-use-bytes":18120704,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-07T23:33:53.934055Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4234023589,"revision":5484,"compact-revision":2850}