{"level":"warn","ts":"2026-07-14T17:32:10.572522Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T17:32:10.572618Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.119: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.119:2380","--initial-cluster=instance=https://199.19.213.119:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.119:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.119: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-07-14T17:32:10.572696Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T17:32:10.572706Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-07-14T17:32:10.572732Z","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-07-14T17:32:10.573504Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"]} {"level":"info","ts":"2026-07-14T17:32:10.573618Z","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.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.119: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-07-14T17:32:10.578597Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.69282ms"} {"level":"info","ts":"2026-07-14T17:32:10.585258Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ec9077c81c499df2","cluster-id":"7e82d3f5f740d3fb"} {"level":"info","ts":"2026-07-14T17:32:10.585325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T17:32:10.585349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 0"} {"level":"info","ts":"2026-07-14T17:32:10.585359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ec9077c81c499df2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T17:32:10.585368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 1"} {"level":"info","ts":"2026-07-14T17:32:10.585405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"warn","ts":"2026-07-14T17:32:10.598366Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T17:32:10.600005Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T17:32:10.602072Z","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-07-14T17:32:10.604594Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ec9077c81c499df2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T17:32:10.607707Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ec9077c81c499df2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T17:32:10.607655Z","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-07-14T17:32:10.607926Z","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-07-14T17:32:10.607963Z","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-07-14T17:32:10.608902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"info","ts":"2026-07-14T17:32:10.608975Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","added-peer-id":"ec9077c81c499df2","added-peer-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-07-14T17:32:10.609471Z","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-07-14T17:32:10.609618Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ec9077c81c499df2","initial-advertise-peer-urls":["https://199.19.213.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T17:32:10.609642Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T17:32:10.609702Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-07-14T17:32:10.609713Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-07-14T17:32:10.685601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T17:32:10.685718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T17:32:10.685744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgPreVoteResp from ec9077c81c499df2 at term 1"} {"level":"info","ts":"2026-07-14T17:32:10.685765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became candidate at term 2"} {"level":"info","ts":"2026-07-14T17:32:10.685782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgVoteResp from ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-07-14T17:32:10.685798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became leader at term 2"} {"level":"info","ts":"2026-07-14T17:32:10.685812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ec9077c81c499df2 elected leader ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-07-14T17:32:10.686446Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:32:10.68719Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ec9077c81c499df2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.119:2379]}","request-path":"/0/members/ec9077c81c499df2/attributes","cluster-id":"7e82d3f5f740d3fb","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T17:32:10.687202Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:32:10.687579Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T17:32:10.68765Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T17:32:10.689949Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:32:10.690179Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.119:2379"} {"level":"info","ts":"2026-07-14T17:32:10.69273Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T17:32:10.697797Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:32:10.697876Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:32:10.697902Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:32:15.502636Z","caller":"traceutil/trace.go:171","msg":"trace[1637165355] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"103.551226ms","start":"2026-07-14T17:32:15.399059Z","end":"2026-07-14T17:32:15.50261Z","steps":["trace[1637165355] 'process raft request' (duration: 103.394619ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:15.50476Z","caller":"traceutil/trace.go:171","msg":"trace[1282102518] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"100.182821ms","start":"2026-07-14T17:32:15.404555Z","end":"2026-07-14T17:32:15.504738Z","steps":["trace[1282102518] 'process raft request' (duration: 100.092838ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:18.916556Z","caller":"traceutil/trace.go:171","msg":"trace[588556586] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"222.244971ms","start":"2026-07-14T17:32:18.694281Z","end":"2026-07-14T17:32:18.916526Z","steps":["trace[588556586] 'process raft request' (duration: 193.197704ms)","trace[588556586] 'compare' (duration: 28.919452ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:32:19.283577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.794257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:32:19.28369Z","caller":"traceutil/trace.go:171","msg":"trace[1653551604] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"355.221978ms","start":"2026-07-14T17:32:18.92845Z","end":"2026-07-14T17:32:19.283672Z","steps":["trace[1653551604] 'process raft request' (duration: 187.63526ms)","trace[1653551604] 'compare' (duration: 166.664653ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:32:19.283743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:32:18.928441Z","time spent":"355.280081ms","remote":"127.0.0.1:51002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:32:19.40183Z","caller":"traceutil/trace.go:171","msg":"trace[556055480] linearizableReadLoop","detail":"{readStateIndex:301; appliedIndex:300; }","duration":"111.031765ms","start":"2026-07-14T17:32:19.290774Z","end":"2026-07-14T17:32:19.401806Z","steps":["trace[556055480] 'read index received' (duration: 106.890576ms)","trace[556055480] 'applied index is now lower than readState.Index' (duration: 4.140249ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:32:19.40199Z","caller":"traceutil/trace.go:171","msg":"trace[2112843429] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"112.789443ms","start":"2026-07-14T17:32:19.289082Z","end":"2026-07-14T17:32:19.401871Z","steps":["trace[2112843429] 'process raft request' (duration: 108.534759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:32:19.402224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.390319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-14T17:32:19.402365Z","caller":"traceutil/trace.go:171","msg":"trace[901833603] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:289; }","duration":"111.560836ms","start":"2026-07-14T17:32:19.290713Z","end":"2026-07-14T17:32:19.402274Z","steps":["trace[901833603] 'agreement among raft nodes before linearized reading' (duration: 111.179792ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:25.602395Z","caller":"traceutil/trace.go:171","msg":"trace[94486740] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"167.580467ms","start":"2026-07-14T17:32:25.43479Z","end":"2026-07-14T17:32:25.60237Z","steps":["trace[94486740] 'process raft request' (duration: 167.422192ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:25.889535Z","caller":"traceutil/trace.go:171","msg":"trace[694226757] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"276.007024ms","start":"2026-07-14T17:32:25.613501Z","end":"2026-07-14T17:32:25.889508Z","steps":["trace[694226757] 'process raft request' (duration: 218.98826ms)","trace[694226757] 'compare' (duration: 56.90571ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:32:26.367122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.777802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:32:26.367188Z","caller":"traceutil/trace.go:171","msg":"trace[1568755953] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:377; }","duration":"425.344027ms","start":"2026-07-14T17:32:25.941834Z","end":"2026-07-14T17:32:26.367178Z","steps":["trace[1568755953] 'read index received' (duration: 232.355927ms)","trace[1568755953] 'applied index is now lower than readState.Index' (duration: 192.98724ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:32:26.367229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.394859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4795"} {"level":"info","ts":"2026-07-14T17:32:26.367241Z","caller":"traceutil/trace.go:171","msg":"trace[294918404] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:364; }","duration":"425.43092ms","start":"2026-07-14T17:32:25.941805Z","end":"2026-07-14T17:32:26.367236Z","steps":["trace[294918404] 'agreement among raft nodes before linearized reading' (duration: 425.398659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:32:26.36726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:32:25.941791Z","time spent":"425.463842ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4818,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-07-14T17:32:26.367309Z","caller":"traceutil/trace.go:171","msg":"trace[769785901] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"467.310558ms","start":"2026-07-14T17:32:25.899964Z","end":"2026-07-14T17:32:26.367274Z","steps":["trace[769785901] 'process raft request' (duration: 274.217305ms)","trace[769785901] 'compare' (duration: 192.70406ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:32:26.367559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:32:25.899914Z","time spent":"467.459614ms","remote":"127.0.0.1:51080","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-07-14T17:32:26.377139Z","caller":"traceutil/trace.go:171","msg":"trace[1952710066] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"209.466078ms","start":"2026-07-14T17:32:26.167654Z","end":"2026-07-14T17:32:26.37712Z","steps":["trace[1952710066] 'process raft request' (duration: 209.368624ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:36.047398Z","caller":"traceutil/trace.go:171","msg":"trace[249174012] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"115.310506ms","start":"2026-07-14T17:32:35.932068Z","end":"2026-07-14T17:32:36.047378Z","steps":["trace[249174012] 'process raft request' (duration: 115.185612ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.26337Z","caller":"traceutil/trace.go:171","msg":"trace[1434939291] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"143.886918ms","start":"2026-07-14T17:32:51.119466Z","end":"2026-07-14T17:32:51.263353Z","steps":["trace[1434939291] 'process raft request' (duration: 143.802076ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.41263Z","caller":"traceutil/trace.go:171","msg":"trace[873495767] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"280.035331ms","start":"2026-07-14T17:32:51.132537Z","end":"2026-07-14T17:32:51.412572Z","steps":["trace[873495767] 'process raft request' (duration: 279.922208ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.412642Z","caller":"traceutil/trace.go:171","msg":"trace[2057444976] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"279.29247ms","start":"2026-07-14T17:32:51.133296Z","end":"2026-07-14T17:32:51.412588Z","steps":["trace[2057444976] 'process raft request' (duration: 279.200878ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.412674Z","caller":"traceutil/trace.go:171","msg":"trace[2028921998] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"277.806859ms","start":"2026-07-14T17:32:51.134798Z","end":"2026-07-14T17:32:51.412605Z","steps":["trace[2028921998] 'process raft request' (duration: 277.734707ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.412882Z","caller":"traceutil/trace.go:171","msg":"trace[1388825251] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"274.663791ms","start":"2026-07-14T17:32:51.138184Z","end":"2026-07-14T17:32:51.412847Z","steps":["trace[1388825251] 'process raft request' (duration: 274.386703ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.412918Z","caller":"traceutil/trace.go:171","msg":"trace[829956024] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"267.557782ms","start":"2026-07-14T17:32:51.145343Z","end":"2026-07-14T17:32:51.412901Z","steps":["trace[829956024] 'process raft request' (duration: 267.266304ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.413005Z","caller":"traceutil/trace.go:171","msg":"trace[695669181] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"252.662567ms","start":"2026-07-14T17:32:51.160333Z","end":"2026-07-14T17:32:51.412996Z","steps":["trace[695669181] 'process raft request' (duration: 252.369999ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.413026Z","caller":"traceutil/trace.go:171","msg":"trace[45374069] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"241.70719ms","start":"2026-07-14T17:32:51.171309Z","end":"2026-07-14T17:32:51.413016Z","steps":["trace[45374069] 'process raft request' (duration: 241.453113ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.413046Z","caller":"traceutil/trace.go:171","msg":"trace[1870123876] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"225.894159ms","start":"2026-07-14T17:32:51.187125Z","end":"2026-07-14T17:32:51.413019Z","steps":["trace[1870123876] 'process raft request' (duration: 225.800356ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.413197Z","caller":"traceutil/trace.go:171","msg":"trace[988551465] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"287.013666ms","start":"2026-07-14T17:32:51.125515Z","end":"2026-07-14T17:32:51.412528Z","steps":["trace[988551465] 'process raft request' (duration: 286.901373ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.412541Z","caller":"traceutil/trace.go:171","msg":"trace[1198050132] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"288.187239ms","start":"2026-07-14T17:32:51.124312Z","end":"2026-07-14T17:32:51.412499Z","steps":["trace[1198050132] 'process raft request' (duration: 287.968793ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.424126Z","caller":"traceutil/trace.go:171","msg":"trace[1164354845] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"155.106502ms","start":"2026-07-14T17:32:51.268991Z","end":"2026-07-14T17:32:51.424097Z","steps":["trace[1164354845] 'process raft request' (duration: 154.648549ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.424268Z","caller":"traceutil/trace.go:171","msg":"trace[443159374] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"154.598387ms","start":"2026-07-14T17:32:51.26966Z","end":"2026-07-14T17:32:51.424258Z","steps":["trace[443159374] 'process raft request' (duration: 154.237077ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.424342Z","caller":"traceutil/trace.go:171","msg":"trace[1849742693] transaction","detail":"{read_only:false; number_of_response:1; response_revision:502; }","duration":"154.596818ms","start":"2026-07-14T17:32:51.26974Z","end":"2026-07-14T17:32:51.424337Z","steps":["trace[1849742693] 'process raft request' (duration: 154.295729ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:51.424402Z","caller":"traceutil/trace.go:171","msg":"trace[371551978] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"105.600959ms","start":"2026-07-14T17:32:51.318797Z","end":"2026-07-14T17:32:51.424398Z","steps":["trace[371551978] 'process raft request' (duration: 105.303621ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:56.825782Z","caller":"traceutil/trace.go:171","msg":"trace[38749409] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"294.479057ms","start":"2026-07-14T17:32:56.531267Z","end":"2026-07-14T17:32:56.825747Z","steps":["trace[38749409] 'read index received' (duration: 288.160459ms)","trace[38749409] 'applied index is now lower than readState.Index' (duration: 6.316798ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:32:56.826111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.816536ms","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-07-14T17:32:56.826175Z","caller":"traceutil/trace.go:171","msg":"trace[1853029023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"294.927759ms","start":"2026-07-14T17:32:56.531229Z","end":"2026-07-14T17:32:56.826157Z","steps":["trace[1853029023] 'agreement among raft nodes before linearized reading' (duration: 294.668602ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:56.826249Z","caller":"traceutil/trace.go:171","msg":"trace[1634550133] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"297.402636ms","start":"2026-07-14T17:32:56.5288Z","end":"2026-07-14T17:32:56.826203Z","steps":["trace[1634550133] 'process raft request' (duration: 290.723557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:32:57.200122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.256635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5khxs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-14T17:32:57.200202Z","caller":"traceutil/trace.go:171","msg":"trace[761278853] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5khxs; range_end:; response_count:1; response_revision:593; }","duration":"140.382578ms","start":"2026-07-14T17:32:57.059799Z","end":"2026-07-14T17:32:57.200181Z","steps":["trace[761278853] 'range keys from in-memory index tree' (duration: 140.030188ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:57.596287Z","caller":"traceutil/trace.go:171","msg":"trace[528580981] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"371.895871ms","start":"2026-07-14T17:32:57.224366Z","end":"2026-07-14T17:32:57.596261Z","steps":["trace[528580981] 'process raft request' (duration: 371.533611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:32:57.597082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:32:57.224341Z","time spent":"372.347212ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:32:58.260403Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334450226585093,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:32:58.362402Z","caller":"traceutil/trace.go:171","msg":"trace[104933832] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"602.677891ms","start":"2026-07-14T17:32:57.759674Z","end":"2026-07-14T17:32:58.362352Z","steps":["trace[104933832] 'read index received' (duration: 600.532455ms)","trace[104933832] 'applied index is now lower than readState.Index' (duration: 2.144696ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:32:58.36477Z","caller":"traceutil/trace.go:171","msg":"trace[894133498] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.099458868s","start":"2026-07-14T17:32:57.265294Z","end":"2026-07-14T17:32:58.364753Z","steps":["trace[894133498] 'process raft request' (duration: 1.095107134s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:32:58.364937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:32:57.265256Z","time spent":"1.099562631s","remote":"127.0.0.1:50878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:32:58.365141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.485185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-14T17:32:58.365242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.361019ms","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-07-14T17:32:58.365308Z","caller":"traceutil/trace.go:171","msg":"trace[2013766625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"155.445942ms","start":"2026-07-14T17:32:58.209831Z","end":"2026-07-14T17:32:58.365276Z","steps":["trace[2013766625] 'agreement among raft nodes before linearized reading' (duration: 155.319038ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:58.3653Z","caller":"traceutil/trace.go:171","msg":"trace[1543895439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"605.611298ms","start":"2026-07-14T17:32:57.759605Z","end":"2026-07-14T17:32:58.365216Z","steps":["trace[1543895439] 'agreement among raft nodes before linearized reading' (duration: 605.401602ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:32:58.36537Z","caller":"traceutil/trace.go:171","msg":"trace[798265981] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.078080432s","start":"2026-07-14T17:32:57.287221Z","end":"2026-07-14T17:32:58.365301Z","steps":["trace[798265981] 'process raft request' (duration: 1.075048923s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:32:58.36539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:32:57.759583Z","time spent":"605.796453ms","remote":"127.0.0.1:51080","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-07-14T17:32:58.365523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:32:57.287195Z","time spent":"1.078244206s","remote":"127.0.0.1:51080","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-07-14T17:33:00.350935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.654718803s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:33:00.351229Z","caller":"traceutil/trace.go:171","msg":"trace[560932619] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.927719032s","start":"2026-07-14T17:32:58.423442Z","end":"2026-07-14T17:33:00.351161Z","steps":["trace[560932619] 'process raft request' (duration: 272.717742ms)","trace[560932619] 'compare' (duration: 1.654406725s)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:33:00.351297Z","caller":"traceutil/trace.go:171","msg":"trace[378641550] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"1.513774632s","start":"2026-07-14T17:32:58.837503Z","end":"2026-07-14T17:33:00.351278Z","steps":["trace[378641550] 'read index received' (duration: 36.211µs)","trace[378641550] 'applied index is now lower than readState.Index' (duration: 1.513735931s)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:33:00.351354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:32:58.423407Z","time spent":"1.927883817s","remote":"127.0.0.1:51178","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-07-14T17:33:00.365025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.527518607s","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-07-14T17:33:00.365073Z","caller":"traceutil/trace.go:171","msg":"trace[2008740971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"1.527620669s","start":"2026-07-14T17:32:58.837443Z","end":"2026-07-14T17:33:00.365063Z","steps":["trace[2008740971] 'agreement among raft nodes before linearized reading' (duration: 1.513976807s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:33:00.365102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:32:58.83742Z","time spent":"1.527675461s","remote":"127.0.0.1:51080","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-07-14T17:33:00.365188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427987533s","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-07-14T17:33:00.365246Z","caller":"traceutil/trace.go:171","msg":"trace[556991950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"1.428092195s","start":"2026-07-14T17:32:58.937136Z","end":"2026-07-14T17:33:00.365228Z","steps":["trace[556991950] 'agreement among raft nodes before linearized reading' (duration: 1.427950982s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:33:00.365296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.632041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:33:00.365324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:32:58.93711Z","time spent":"1.428198859s","remote":"127.0.0.1:51080","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-07-14T17:33:00.365353Z","caller":"traceutil/trace.go:171","msg":"trace[1314087320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"998.752564ms","start":"2026-07-14T17:32:59.366585Z","end":"2026-07-14T17:33:00.365338Z","steps":["trace[1314087320] 'agreement among raft nodes before linearized reading' (duration: 998.568089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:33:00.365383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:32:59.366562Z","time spent":"998.816756ms","remote":"127.0.0.1:50768","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-07-14T17:33:01.550419Z","caller":"traceutil/trace.go:171","msg":"trace[1124997682] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"183.868337ms","start":"2026-07-14T17:33:01.366529Z","end":"2026-07-14T17:33:01.550397Z","steps":["trace[1124997682] 'read index received' (duration: 182.970774ms)","trace[1124997682] 'applied index is now lower than readState.Index' (duration: 896.693µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:33:01.5505Z","caller":"traceutil/trace.go:171","msg":"trace[831848184] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"219.831627ms","start":"2026-07-14T17:33:01.330617Z","end":"2026-07-14T17:33:01.550448Z","steps":["trace[831848184] 'process raft request' (duration: 218.829432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:33:01.550552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.00327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:33:01.550583Z","caller":"traceutil/trace.go:171","msg":"trace[1048940984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"184.071371ms","start":"2026-07-14T17:33:01.3665Z","end":"2026-07-14T17:33:01.550571Z","steps":["trace[1048940984] 'agreement among raft nodes before linearized reading' (duration: 183.983769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:33:16.397974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.051436ms","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-07-14T17:33:16.398084Z","caller":"traceutil/trace.go:171","msg":"trace[1926956967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:764; }","duration":"485.21145ms","start":"2026-07-14T17:33:15.912847Z","end":"2026-07-14T17:33:16.398058Z","steps":["trace[1926956967] 'range keys from in-memory index tree' (duration: 484.864282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:33:16.39812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:33:15.912823Z","time spent":"485.288451ms","remote":"127.0.0.1:51080","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-07-14T17:33:16.398043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.028315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g6xsf\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-14T17:33:16.398288Z","caller":"traceutil/trace.go:171","msg":"trace[74454049] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g6xsf; range_end:; response_count:1; response_revision:764; }","duration":"237.406684ms","start":"2026-07-14T17:33:16.160849Z","end":"2026-07-14T17:33:16.398256Z","steps":["trace[74454049] 'range keys from in-memory index tree' (duration: 236.716688ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:16.535031Z","caller":"traceutil/trace.go:171","msg":"trace[984853215] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"130.960456ms","start":"2026-07-14T17:33:16.404049Z","end":"2026-07-14T17:33:16.53501Z","steps":["trace[984853215] 'process raft request' (duration: 130.834983ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:16.539349Z","caller":"traceutil/trace.go:171","msg":"trace[423188255] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"131.950918ms","start":"2026-07-14T17:33:16.407371Z","end":"2026-07-14T17:33:16.539322Z","steps":["trace[423188255] 'process raft request' (duration: 131.755653ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:23.546466Z","caller":"traceutil/trace.go:171","msg":"trace[1018729166] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"131.119151ms","start":"2026-07-14T17:33:23.415283Z","end":"2026-07-14T17:33:23.546402Z","steps":["trace[1018729166] 'process raft request' (duration: 130.874536ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:23.546525Z","caller":"traceutil/trace.go:171","msg":"trace[737313664] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"128.522875ms","start":"2026-07-14T17:33:23.417963Z","end":"2026-07-14T17:33:23.546486Z","steps":["trace[737313664] 'process raft request' (duration: 128.356612ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:33:48.121831Z","caller":"traceutil/trace.go:171","msg":"trace[1827725070] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"392.5419ms","start":"2026-07-14T17:33:47.729264Z","end":"2026-07-14T17:33:48.121806Z","steps":["trace[1827725070] 'process raft request' (duration: 392.411207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:33:48.121971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:33:47.729245Z","time spent":"392.656801ms","remote":"127.0.0.1:50958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6385,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:33:48.122388Z","caller":"traceutil/trace.go:171","msg":"trace[123395460] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"349.867468ms","start":"2026-07-14T17:33:47.772499Z","end":"2026-07-14T17:33:48.122367Z","steps":["trace[123395460] 'process raft request' (duration: 349.749816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:33:48.122547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:33:47.772477Z","time spent":"349.98083ms","remote":"127.0.0.1:51080","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-07-14T17:34:08.91319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.761708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:34:08.913286Z","caller":"traceutil/trace.go:171","msg":"trace[1972218709] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1417; }","duration":"101.91183ms","start":"2026-07-14T17:34:08.811353Z","end":"2026-07-14T17:34:08.913265Z","steps":["trace[1972218709] 'count revisions from in-memory index tree' (duration: 101.635855ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:09.043696Z","caller":"traceutil/trace.go:171","msg":"trace[1348945834] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"126.224494ms","start":"2026-07-14T17:34:08.917443Z","end":"2026-07-14T17:34:09.043668Z","steps":["trace[1348945834] 'process raft request' (duration: 126.061101ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:09.184088Z","caller":"traceutil/trace.go:171","msg":"trace[630814724] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"265.289783ms","start":"2026-07-14T17:34:08.91877Z","end":"2026-07-14T17:34:09.18406Z","steps":["trace[630814724] 'process raft request' (duration: 265.246033ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:09.184115Z","caller":"traceutil/trace.go:171","msg":"trace[1172237571] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"266.29815ms","start":"2026-07-14T17:34:08.91777Z","end":"2026-07-14T17:34:09.184068Z","steps":["trace[1172237571] 'process raft request' (duration: 265.950984ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:09.184227Z","caller":"traceutil/trace.go:171","msg":"trace[753069867] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"266.399371ms","start":"2026-07-14T17:34:08.917774Z","end":"2026-07-14T17:34:09.184174Z","steps":["trace[753069867] 'process raft request' (duration: 266.141997ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:09.184276Z","caller":"traceutil/trace.go:171","msg":"trace[1914409677] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"266.029635ms","start":"2026-07-14T17:34:08.918211Z","end":"2026-07-14T17:34:09.184241Z","steps":["trace[1914409677] 'process raft request' (duration: 265.772451ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:11.165108Z","caller":"traceutil/trace.go:171","msg":"trace[1534270620] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"112.94948ms","start":"2026-07-14T17:34:11.052123Z","end":"2026-07-14T17:34:11.165072Z","steps":["trace[1534270620] 'process raft request' (duration: 31.024171ms)","trace[1534270620] 'compare' (duration: 81.768486ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:34:35.786509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.053852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:34:35.786705Z","caller":"traceutil/trace.go:171","msg":"trace[1427714091] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1903; }","duration":"379.871281ms","start":"2026-07-14T17:34:35.406813Z","end":"2026-07-14T17:34:35.786685Z","steps":["trace[1427714091] 'read index received' (duration: 162.481873ms)","trace[1427714091] 'applied index is now lower than readState.Index' (duration: 217.388218ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:34:35.786831Z","caller":"traceutil/trace.go:171","msg":"trace[949214501] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"392.43381ms","start":"2026-07-14T17:34:35.394384Z","end":"2026-07-14T17:34:35.786817Z","steps":["trace[949214501] 'process raft request' (duration: 174.975151ms)","trace[949214501] 'compare' (duration: 216.91476ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:34:35.786972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:34:35.394361Z","time spent":"392.566441ms","remote":"127.0.0.1:51080","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-07-14T17:34:35.787194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.554107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-14T17:34:35.787313Z","caller":"traceutil/trace.go:171","msg":"trace[894530598] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1832; }","duration":"254.696909ms","start":"2026-07-14T17:34:35.532588Z","end":"2026-07-14T17:34:35.787285Z","steps":["trace[894530598] 'agreement among raft nodes before linearized reading' (duration: 254.356704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:34:35.787307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.468059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-14T17:34:35.787376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.90693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6ea03640-8aae-4b86-a240-d02ef9dae746\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-14T17:34:35.787439Z","caller":"traceutil/trace.go:171","msg":"trace[402646093] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1832; }","duration":"380.647662ms","start":"2026-07-14T17:34:35.406758Z","end":"2026-07-14T17:34:35.787406Z","steps":["trace[402646093] 'agreement among raft nodes before linearized reading' (duration: 380.360908ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:35.787486Z","caller":"traceutil/trace.go:171","msg":"trace[547587256] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6ea03640-8aae-4b86-a240-d02ef9dae746; range_end:; response_count:1; response_revision:1832; }","duration":"235.057583ms","start":"2026-07-14T17:34:35.552406Z","end":"2026-07-14T17:34:35.787463Z","steps":["trace[547587256] 'agreement among raft nodes before linearized reading' (duration: 234.741409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:34:35.787517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:34:35.406732Z","time spent":"380.768794ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-14T17:34:36.243119Z","caller":"traceutil/trace.go:171","msg":"trace[765376089] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"449.014709ms","start":"2026-07-14T17:34:35.794066Z","end":"2026-07-14T17:34:36.243081Z","steps":["trace[765376089] 'process raft request' (duration: 349.753992ms)","trace[765376089] 'compare' (duration: 98.831011ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:34:36.243323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:34:35.794037Z","time spent":"449.174372ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:34:36.266957Z","caller":"traceutil/trace.go:171","msg":"trace[1807931712] linearizableReadLoop","detail":"{readStateIndex:1906; appliedIndex:1904; }","duration":"279.48972ms","start":"2026-07-14T17:34:35.987445Z","end":"2026-07-14T17:34:36.266935Z","steps":["trace[1807931712] 'read index received' (duration: 156.390495ms)","trace[1807931712] 'applied index is now lower than readState.Index' (duration: 123.098125ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:34:36.26701Z","caller":"traceutil/trace.go:171","msg":"trace[228508384] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"472.642323ms","start":"2026-07-14T17:34:35.794331Z","end":"2026-07-14T17:34:36.266974Z","steps":["trace[228508384] 'process raft request' (duration: 472.464451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:34:36.267113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.743869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:34:36.267115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:34:35.794299Z","time spent":"472.757276ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:34:36.267109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.661602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6ea03640-8aae-4b86-a240-d02ef9dae746\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-07-14T17:34:36.267148Z","caller":"traceutil/trace.go:171","msg":"trace[1807602857] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1834; }","duration":"148.860451ms","start":"2026-07-14T17:34:36.118277Z","end":"2026-07-14T17:34:36.267137Z","steps":["trace[1807602857] 'agreement among raft nodes before linearized reading' (duration: 148.78314ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:36.267175Z","caller":"traceutil/trace.go:171","msg":"trace[269773867] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6ea03640-8aae-4b86-a240-d02ef9dae746; range_end:; response_count:1; response_revision:1834; }","duration":"279.788864ms","start":"2026-07-14T17:34:35.987368Z","end":"2026-07-14T17:34:36.267157Z","steps":["trace[269773867] 'agreement among raft nodes before linearized reading' (duration: 279.646872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:34:36.267189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.199946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:34:36.26729Z","caller":"traceutil/trace.go:171","msg":"trace[2080799682] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1834; }","duration":"129.374539ms","start":"2026-07-14T17:34:36.137893Z","end":"2026-07-14T17:34:36.267267Z","steps":["trace[2080799682] 'agreement among raft nodes before linearized reading' (duration: 129.209326ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:37.11443Z","caller":"traceutil/trace.go:171","msg":"trace[1677605760] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"168.767494ms","start":"2026-07-14T17:34:36.945634Z","end":"2026-07-14T17:34:37.114401Z","steps":["trace[1677605760] 'process raft request' (duration: 168.611711ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:34:50.130482Z","caller":"traceutil/trace.go:171","msg":"trace[1210651496] linearizableReadLoop","detail":"{readStateIndex:2025; appliedIndex:2024; }","duration":"248.190653ms","start":"2026-07-14T17:34:49.882257Z","end":"2026-07-14T17:34:50.130447Z","steps":["trace[1210651496] 'read index received' (duration: 247.917639ms)","trace[1210651496] 'applied index is now lower than readState.Index' (duration: 271.384µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:34:50.130542Z","caller":"traceutil/trace.go:171","msg":"trace[590119790] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"280.696242ms","start":"2026-07-14T17:34:49.849817Z","end":"2026-07-14T17:34:50.130514Z","steps":["trace[590119790] 'process raft request' (duration: 280.461218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:34:50.130771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.494838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-14T17:34:50.130834Z","caller":"traceutil/trace.go:171","msg":"trace[945437863] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1950; }","duration":"248.614149ms","start":"2026-07-14T17:34:49.882202Z","end":"2026-07-14T17:34:50.130816Z","steps":["trace[945437863] 'agreement among raft nodes before linearized reading' (duration: 248.370916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:05.706815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.36298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:35:05.707011Z","caller":"traceutil/trace.go:171","msg":"trace[1351705153] linearizableReadLoop","detail":"{readStateIndex:2127; appliedIndex:2125; }","duration":"255.524992ms","start":"2026-07-14T17:35:05.451469Z","end":"2026-07-14T17:35:05.706994Z","steps":["trace[1351705153] 'read index received' (duration: 8.809522ms)","trace[1351705153] 'applied index is now lower than readState.Index' (duration: 246.7142ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:35:05.707106Z","caller":"traceutil/trace.go:171","msg":"trace[822573753] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"256.190122ms","start":"2026-07-14T17:35:05.450904Z","end":"2026-07-14T17:35:05.707094Z","steps":["trace[822573753] 'process raft request' (duration: 132.454366ms)","trace[822573753] 'compare' (duration: 123.109937ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:35:05.707508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.28476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-07-14T17:35:05.707555Z","caller":"traceutil/trace.go:171","msg":"trace[1614274913] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2050; }","duration":"232.376002ms","start":"2026-07-14T17:35:05.475163Z","end":"2026-07-14T17:35:05.707539Z","steps":["trace[1614274913] 'agreement among raft nodes before linearized reading' (duration: 232.22333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:05.707591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.07139ms","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-07-14T17:35:05.707767Z","caller":"traceutil/trace.go:171","msg":"trace[1233686083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2050; }","duration":"256.309223ms","start":"2026-07-14T17:35:05.451426Z","end":"2026-07-14T17:35:05.707735Z","steps":["trace[1233686083] 'agreement among raft nodes before linearized reading' (duration: 255.784586ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:35:05.707526Z","caller":"traceutil/trace.go:171","msg":"trace[1690008315] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"256.009319ms","start":"2026-07-14T17:35:05.45146Z","end":"2026-07-14T17:35:05.707469Z","steps":["trace[1690008315] 'process raft request' (duration: 255.449981ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:35:10.710495Z","caller":"traceutil/trace.go:171","msg":"trace[897034237] linearizableReadLoop","detail":"{readStateIndex:2155; appliedIndex:2154; }","duration":"402.721434ms","start":"2026-07-14T17:35:10.307743Z","end":"2026-07-14T17:35:10.710464Z","steps":["trace[897034237] 'read index received' (duration: 402.624143ms)","trace[897034237] 'applied index is now lower than readState.Index' (duration: 96.061µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:35:10.710547Z","caller":"traceutil/trace.go:171","msg":"trace[1112624133] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"451.20557ms","start":"2026-07-14T17:35:10.259314Z","end":"2026-07-14T17:35:10.71052Z","steps":["trace[1112624133] 'process raft request' (duration: 450.847255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:10.710692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:10.259287Z","time spent":"451.320551ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:35:10.710816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.036228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-14T17:35:10.710995Z","caller":"traceutil/trace.go:171","msg":"trace[1788886837] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2076; }","duration":"403.197981ms","start":"2026-07-14T17:35:10.307683Z","end":"2026-07-14T17:35:10.710881Z","steps":["trace[1788886837] 'agreement among raft nodes before linearized reading' (duration: 402.907897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:10.711083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:10.30765Z","time spent":"403.415964ms","remote":"127.0.0.1:33790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-14T17:35:10.77483Z","caller":"traceutil/trace.go:171","msg":"trace[1231710352] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"233.883699ms","start":"2026-07-14T17:35:10.540901Z","end":"2026-07-14T17:35:10.774785Z","steps":["trace[1231710352] 'process raft request' (duration: 233.638815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:10.774974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.802745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-14T17:35:10.775102Z","caller":"traceutil/trace.go:171","msg":"trace[1599586266] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2077; }","duration":"359.993459ms","start":"2026-07-14T17:35:10.415084Z","end":"2026-07-14T17:35:10.775078Z","steps":["trace[1599586266] 'agreement among raft nodes before linearized reading' (duration: 359.665464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:10.775155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:10.415063Z","time spent":"360.08278ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T17:35:10.947557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.623509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:35:10.948085Z","caller":"traceutil/trace.go:171","msg":"trace[1732604365] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"167.841952ms","start":"2026-07-14T17:35:10.780188Z","end":"2026-07-14T17:35:10.94803Z","steps":["trace[1732604365] 'process raft request' (duration: 26.594514ms)","trace[1732604365] 'compare' (duration: 140.423907ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:35:11.46423Z","caller":"traceutil/trace.go:171","msg":"trace[239439605] linearizableReadLoop","detail":"{readStateIndex:2158; appliedIndex:2157; }","duration":"128.946664ms","start":"2026-07-14T17:35:11.335257Z","end":"2026-07-14T17:35:11.464204Z","steps":["trace[239439605] 'read index received' (duration: 128.888603ms)","trace[239439605] 'applied index is now lower than readState.Index' (duration: 56.911µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:35:11.464385Z","caller":"traceutil/trace.go:171","msg":"trace[1740479461] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"172.201946ms","start":"2026-07-14T17:35:11.29217Z","end":"2026-07-14T17:35:11.464372Z","steps":["trace[1740479461] 'process raft request' (duration: 171.911663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:11.464539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.238368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:35:11.464614Z","caller":"traceutil/trace.go:171","msg":"trace[799099934] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2079; }","duration":"129.395111ms","start":"2026-07-14T17:35:11.335203Z","end":"2026-07-14T17:35:11.464598Z","steps":["trace[799099934] 'agreement among raft nodes before linearized reading' (duration: 129.186878ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:35:11.726554Z","caller":"traceutil/trace.go:171","msg":"trace[1789359764] linearizableReadLoop","detail":"{readStateIndex:2159; appliedIndex:2158; }","duration":"262.23715ms","start":"2026-07-14T17:35:11.464291Z","end":"2026-07-14T17:35:11.726528Z","steps":["trace[1789359764] 'read index received' (duration: 231.457629ms)","trace[1789359764] 'applied index is now lower than readState.Index' (duration: 30.778601ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:35:11.726678Z","caller":"traceutil/trace.go:171","msg":"trace[845750270] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"337.874754ms","start":"2026-07-14T17:35:11.388791Z","end":"2026-07-14T17:35:11.726666Z","steps":["trace[845750270] 'process raft request' (duration: 307.010222ms)","trace[845750270] 'compare' (duration: 30.619409ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:35:11.726778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:11.388757Z","time spent":"337.951746ms","remote":"127.0.0.1:51080","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-07-14T17:35:11.726884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.854435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:35:11.726958Z","caller":"traceutil/trace.go:171","msg":"trace[536945773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2080; }","duration":"359.961057ms","start":"2026-07-14T17:35:11.366979Z","end":"2026-07-14T17:35:11.72694Z","steps":["trace[536945773] 'agreement among raft nodes before linearized reading' (duration: 359.853296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:11.72702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:11.366963Z","time spent":"360.034048ms","remote":"127.0.0.1:50768","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-07-14T17:35:11.727012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.944069ms","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-07-14T17:35:11.727097Z","caller":"traceutil/trace.go:171","msg":"trace[121970575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2080; }","duration":"214.088531ms","start":"2026-07-14T17:35:11.51299Z","end":"2026-07-14T17:35:11.727078Z","steps":["trace[121970575] 'agreement among raft nodes before linearized reading' (duration: 213.850978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:11.727236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.805617ms","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-07-14T17:35:11.727286Z","caller":"traceutil/trace.go:171","msg":"trace[1987065612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2080; }","duration":"186.897978ms","start":"2026-07-14T17:35:11.540375Z","end":"2026-07-14T17:35:11.727273Z","steps":["trace[1987065612] 'agreement among raft nodes before linearized reading' (duration: 186.771557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:12.5499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.528817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:35:12.550149Z","caller":"traceutil/trace.go:171","msg":"trace[1902037986] linearizableReadLoop","detail":"{readStateIndex:2161; appliedIndex:2159; }","duration":"815.992125ms","start":"2026-07-14T17:35:11.734139Z","end":"2026-07-14T17:35:12.550131Z","steps":["trace[1902037986] 'read index received' (duration: 128.161394ms)","trace[1902037986] 'applied index is now lower than readState.Index' (duration: 687.829431ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:35:12.550255Z","caller":"traceutil/trace.go:171","msg":"trace[457172443] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"818.635752ms","start":"2026-07-14T17:35:11.731608Z","end":"2026-07-14T17:35:12.550244Z","steps":["trace[457172443] 'process raft request' (duration: 130.675709ms)","trace[457172443] 'compare' (duration: 687.184032ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:35:12.550327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:11.731569Z","time spent":"818.720163ms","remote":"127.0.0.1:51080","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-07-14T17:35:12.550614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.460052ms","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-07-14T17:35:12.550703Z","caller":"traceutil/trace.go:171","msg":"trace[1545108547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2082; }","duration":"816.590184ms","start":"2026-07-14T17:35:11.734095Z","end":"2026-07-14T17:35:12.550685Z","steps":["trace[1545108547] 'agreement among raft nodes before linearized reading' (duration: 816.35185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:12.55075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:11.734074Z","time spent":"816.666224ms","remote":"127.0.0.1:51080","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-07-14T17:35:12.550966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.055907ms","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-07-14T17:35:12.551016Z","caller":"traceutil/trace.go:171","msg":"trace[2023852886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2082; }","duration":"811.115899ms","start":"2026-07-14T17:35:11.739887Z","end":"2026-07-14T17:35:12.551003Z","steps":["trace[2023852886] 'agreement among raft nodes before linearized reading' (duration: 811.003128ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:35:12.551033Z","caller":"traceutil/trace.go:171","msg":"trace[1099520102] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"819.154479ms","start":"2026-07-14T17:35:11.731864Z","end":"2026-07-14T17:35:12.551019Z","steps":["trace[1099520102] 'process raft request' (duration: 818.177936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:12.551051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:11.739879Z","time spent":"811.16405ms","remote":"127.0.0.1:51080","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-07-14T17:35:12.551072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.416881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"warn","ts":"2026-07-14T17:35:12.551125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:11.731839Z","time spent":"819.22747ms","remote":"127.0.0.1:51080","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-07-14T17:35:12.551163Z","caller":"traceutil/trace.go:171","msg":"trace[2084512628] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2082; }","duration":"759.549013ms","start":"2026-07-14T17:35:11.79159Z","end":"2026-07-14T17:35:12.551139Z","steps":["trace[2084512628] 'agreement among raft nodes before linearized reading' (duration: 759.272469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:12.550643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.365581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-14T17:35:12.551208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:11.791571Z","time spent":"759.626804ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-14T17:35:12.551238Z","caller":"traceutil/trace.go:171","msg":"trace[1104406358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2082; }","duration":"817.016419ms","start":"2026-07-14T17:35:11.73421Z","end":"2026-07-14T17:35:12.551227Z","steps":["trace[1104406358] 'agreement among raft nodes before linearized reading' (duration: 816.32565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:12.551276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:11.73419Z","time spent":"817.074191ms","remote":"127.0.0.1:51080","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-07-14T17:35:12.551347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.018967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-14T17:35:12.551382Z","caller":"traceutil/trace.go:171","msg":"trace[409191248] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2082; }","duration":"283.073307ms","start":"2026-07-14T17:35:12.268299Z","end":"2026-07-14T17:35:12.551372Z","steps":["trace[409191248] 'agreement among raft nodes before linearized reading' (duration: 283.012406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:12.552058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.45232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:112851"} {"level":"info","ts":"2026-07-14T17:35:12.552138Z","caller":"traceutil/trace.go:171","msg":"trace[2089383032] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2082; }","duration":"285.537791ms","start":"2026-07-14T17:35:12.266582Z","end":"2026-07-14T17:35:12.55212Z","steps":["trace[2089383032] 'agreement among raft nodes before linearized reading' (duration: 285.233877ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:35:12.968384Z","caller":"traceutil/trace.go:171","msg":"trace[749550040] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"412.390294ms","start":"2026-07-14T17:35:12.555934Z","end":"2026-07-14T17:35:12.968324Z","steps":["trace[749550040] 'process raft request' (duration: 322.444885ms)","trace[749550040] 'compare' (duration: 89.803767ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:35:12.968597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:12.555922Z","time spent":"412.605687ms","remote":"127.0.0.1:51080","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-07-14T17:35:13.310212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.463985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:35:13.310731Z","caller":"traceutil/trace.go:171","msg":"trace[1464200842] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"754.364618ms","start":"2026-07-14T17:35:12.556336Z","end":"2026-07-14T17:35:13.3107Z","steps":["trace[1464200842] 'process raft request' (duration: 627.285695ms)","trace[1464200842] 'compare' (duration: 126.162931ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:35:13.310848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:12.556311Z","time spent":"754.484879ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:35:13.311607Z","caller":"traceutil/trace.go:171","msg":"trace[124507510] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"755.039187ms","start":"2026-07-14T17:35:12.556551Z","end":"2026-07-14T17:35:13.31159Z","steps":["trace[124507510] 'process raft request' (duration: 754.932716ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:35:13.31175Z","caller":"traceutil/trace.go:171","msg":"trace[2097266521] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"755.31739ms","start":"2026-07-14T17:35:12.556371Z","end":"2026-07-14T17:35:13.311688Z","steps":["trace[2097266521] 'process raft request' (duration: 754.872044ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:35:13.311867Z","caller":"traceutil/trace.go:171","msg":"trace[242888546] linearizableReadLoop","detail":"{readStateIndex:2165; appliedIndex:2161; }","duration":"754.342158ms","start":"2026-07-14T17:35:12.557512Z","end":"2026-07-14T17:35:13.311854Z","steps":["trace[242888546] 'read index received' (duration: 320.885194ms)","trace[242888546] 'applied index is now lower than readState.Index' (duration: 433.456114ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:35:13.311999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:12.556359Z","time spent":"755.496444ms","remote":"127.0.0.1:51080","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-07-14T17:35:13.312052Z","caller":"traceutil/trace.go:171","msg":"trace[295455954] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"531.412792ms","start":"2026-07-14T17:35:12.78063Z","end":"2026-07-14T17:35:13.312042Z","steps":["trace[295455954] 'process raft request' (duration: 531.157729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:13.312147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:12.780608Z","time spent":"531.488053ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:35:13.312014Z","caller":"traceutil/trace.go:171","msg":"trace[501378020] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"595.540358ms","start":"2026-07-14T17:35:12.716453Z","end":"2026-07-14T17:35:13.311993Z","steps":["trace[501378020] 'process raft request' (duration: 595.094691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:13.312243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:12.716428Z","time spent":"595.774491ms","remote":"127.0.0.1:51080","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-07-14T17:35:13.31239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.795423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-14T17:35:13.312493Z","caller":"traceutil/trace.go:171","msg":"trace[920289] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2088; }","duration":"754.930335ms","start":"2026-07-14T17:35:12.557538Z","end":"2026-07-14T17:35:13.312469Z","steps":["trace[920289] 'agreement among raft nodes before linearized reading' (duration: 754.677602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:13.312542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:12.557532Z","time spent":"755.000847ms","remote":"127.0.0.1:50842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-07-14T17:35:13.312556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.035697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:112851"} {"level":"info","ts":"2026-07-14T17:35:13.312616Z","caller":"traceutil/trace.go:171","msg":"trace[1773250508] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2088; }","duration":"755.117858ms","start":"2026-07-14T17:35:12.557482Z","end":"2026-07-14T17:35:13.3126Z","steps":["trace[1773250508] 'agreement among raft nodes before linearized reading' (duration: 754.901865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:13.312623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.443841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-07-14T17:35:13.312665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:12.557465Z","time spent":"755.188849ms","remote":"127.0.0.1:50832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":112874,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-07-14T17:35:13.312709Z","caller":"traceutil/trace.go:171","msg":"trace[244120462] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2088; }","duration":"577.619453ms","start":"2026-07-14T17:35:12.73506Z","end":"2026-07-14T17:35:13.31268Z","steps":["trace[244120462] 'agreement among raft nodes before linearized reading' (duration: 577.4156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:13.312751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.462405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-14T17:35:13.312763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:12.735048Z","time spent":"577.704174ms","remote":"127.0.0.1:33790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-14T17:35:13.312809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.020585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:35:13.312825Z","caller":"traceutil/trace.go:171","msg":"trace[342001029] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2088; }","duration":"357.549056ms","start":"2026-07-14T17:35:12.955242Z","end":"2026-07-14T17:35:13.312791Z","steps":["trace[342001029] 'agreement among raft nodes before linearized reading' (duration: 357.349684ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:35:13.312849Z","caller":"traceutil/trace.go:171","msg":"trace[1786429120] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2088; }","duration":"615.108685ms","start":"2026-07-14T17:35:12.697727Z","end":"2026-07-14T17:35:13.312836Z","steps":["trace[1786429120] 'agreement among raft nodes before linearized reading' (duration: 615.033984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:13.312882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:12.697698Z","time spent":"615.176027ms","remote":"127.0.0.1:32864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:35:13.312887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:12.955219Z","time spent":"357.656088ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T17:35:13.312518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.779208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:35:13.312979Z","caller":"traceutil/trace.go:171","msg":"trace[623561294] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2088; }","duration":"576.340186ms","start":"2026-07-14T17:35:12.736613Z","end":"2026-07-14T17:35:13.312953Z","steps":["trace[623561294] 'agreement among raft nodes before linearized reading' (duration: 575.722787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:13.313044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:12.736577Z","time spent":"576.440498ms","remote":"127.0.0.1:49030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:35:13.313405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:35:12.556535Z","time spent":"755.174079ms","remote":"127.0.0.1:51080","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-07-14T17:35:13.517705Z","caller":"traceutil/trace.go:171","msg":"trace[1425642508] linearizableReadLoop","detail":"{readStateIndex:2168; appliedIndex:2167; }","duration":"150.390401ms","start":"2026-07-14T17:35:13.367289Z","end":"2026-07-14T17:35:13.51768Z","steps":["trace[1425642508] 'read index received' (duration: 100.811005ms)","trace[1425642508] 'applied index is now lower than readState.Index' (duration: 49.577886ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:35:13.517825Z","caller":"traceutil/trace.go:171","msg":"trace[1168717228] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"198.83597ms","start":"2026-07-14T17:35:13.318947Z","end":"2026-07-14T17:35:13.517783Z","steps":["trace[1168717228] 'process raft request' (duration: 149.257485ms)","trace[1168717228] 'compare' (duration: 49.354662ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:35:13.517948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.631644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:35:13.51805Z","caller":"traceutil/trace.go:171","msg":"trace[628108484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2089; }","duration":"150.801766ms","start":"2026-07-14T17:35:13.367225Z","end":"2026-07-14T17:35:13.518027Z","steps":["trace[628108484] 'agreement among raft nodes before linearized reading' (duration: 150.625323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:35:13.518203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.640214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:35:13.518291Z","caller":"traceutil/trace.go:171","msg":"trace[464740471] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2089; }","duration":"150.792626ms","start":"2026-07-14T17:35:13.367485Z","end":"2026-07-14T17:35:13.518278Z","steps":["trace[464740471] 'agreement among raft nodes before linearized reading' (duration: 150.592243ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:35:41.601981Z","caller":"traceutil/trace.go:171","msg":"trace[904360669] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"110.350877ms","start":"2026-07-14T17:35:41.491597Z","end":"2026-07-14T17:35:41.601947Z","steps":["trace[904360669] 'process raft request' (duration: 47.891274ms)","trace[904360669] 'compare' (duration: 62.2402ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:35:41.602331Z","caller":"traceutil/trace.go:171","msg":"trace[1989379129] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"110.54503ms","start":"2026-07-14T17:35:41.491749Z","end":"2026-07-14T17:35:41.602294Z","steps":["trace[1989379129] 'process raft request' (duration: 110.151045ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:36:06.877835Z","caller":"traceutil/trace.go:171","msg":"trace[604737642] transaction","detail":"{read_only:false; response_revision:2420; number_of_response:1; }","duration":"168.392963ms","start":"2026-07-14T17:36:06.709411Z","end":"2026-07-14T17:36:06.877804Z","steps":["trace[604737642] 'process raft request' (duration: 95.639338ms)","trace[604737642] 'compare' (duration: 72.533846ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:36:15.608827Z","caller":"traceutil/trace.go:171","msg":"trace[909709037] transaction","detail":"{read_only:false; response_revision:2517; number_of_response:1; }","duration":"231.538936ms","start":"2026-07-14T17:36:15.377267Z","end":"2026-07-14T17:36:15.608806Z","steps":["trace[909709037] 'process raft request' (duration: 230.981185ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:36:49.140413Z","caller":"traceutil/trace.go:171","msg":"trace[136737543] transaction","detail":"{read_only:false; response_revision:2702; number_of_response:1; }","duration":"177.655912ms","start":"2026-07-14T17:36:48.962718Z","end":"2026-07-14T17:36:49.140374Z","steps":["trace[136737543] 'process raft request' (duration: 177.414045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:36:49.412548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.715108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:36:49.412709Z","caller":"traceutil/trace.go:171","msg":"trace[561703073] linearizableReadLoop","detail":"{readStateIndex:2804; appliedIndex:2803; }","duration":"227.367193ms","start":"2026-07-14T17:36:49.185317Z","end":"2026-07-14T17:36:49.412684Z","steps":["trace[561703073] 'read index received' (duration: 68.208281ms)","trace[561703073] 'applied index is now lower than readState.Index' (duration: 159.157482ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:36:49.412836Z","caller":"traceutil/trace.go:171","msg":"trace[2004580778] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"267.381838ms","start":"2026-07-14T17:36:49.145371Z","end":"2026-07-14T17:36:49.412752Z","steps":["trace[2004580778] 'process raft request' (duration: 108.286208ms)","trace[2004580778] 'compare' (duration: 158.525162ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:36:49.412872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.55731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:36:49.412919Z","caller":"traceutil/trace.go:171","msg":"trace[2023434008] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2703; }","duration":"227.648452ms","start":"2026-07-14T17:36:49.185259Z","end":"2026-07-14T17:36:49.412907Z","steps":["trace[2023434008] 'agreement among raft nodes before linearized reading' (duration: 227.559839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:36:49.413039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.300043ms","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-07-14T17:36:49.413123Z","caller":"traceutil/trace.go:171","msg":"trace[1890525141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2703; }","duration":"181.449199ms","start":"2026-07-14T17:36:49.231655Z","end":"2026-07-14T17:36:49.413104Z","steps":["trace[1890525141] 'agreement among raft nodes before linearized reading' (duration: 181.218471ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:38:38.383093Z","caller":"traceutil/trace.go:171","msg":"trace[2050368553] transaction","detail":"{read_only:false; response_revision:3271; number_of_response:1; }","duration":"142.992329ms","start":"2026-07-14T17:38:38.240054Z","end":"2026-07-14T17:38:38.383046Z","steps":["trace[2050368553] 'process raft request' (duration: 127.563826ms)","trace[2050368553] 'compare' (duration: 15.041885ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:38:46.056143Z","caller":"traceutil/trace.go:171","msg":"trace[545817651] transaction","detail":"{read_only:false; response_revision:3318; number_of_response:1; }","duration":"117.84679ms","start":"2026-07-14T17:38:45.93826Z","end":"2026-07-14T17:38:46.056106Z","steps":["trace[545817651] 'process raft request' (duration: 55.307892ms)","trace[545817651] 'compare' (duration: 62.245131ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:39:54.831921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.685882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:39:54.832065Z","caller":"traceutil/trace.go:171","msg":"trace[907756945] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3837; }","duration":"116.876556ms","start":"2026-07-14T17:39:54.715161Z","end":"2026-07-14T17:39:54.832037Z","steps":["trace[907756945] 'count revisions from in-memory index tree' (duration: 116.609091ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:54.83482Z","caller":"traceutil/trace.go:171","msg":"trace[672809498] transaction","detail":"{read_only:false; response_revision:3838; number_of_response:1; }","duration":"115.105201ms","start":"2026-07-14T17:39:54.719687Z","end":"2026-07-14T17:39:54.834792Z","steps":["trace[672809498] 'process raft request' (duration: 114.881087ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:54.857455Z","caller":"traceutil/trace.go:171","msg":"trace[868989943] transaction","detail":"{read_only:false; response_revision:3839; number_of_response:1; }","duration":"134.958509ms","start":"2026-07-14T17:39:54.722473Z","end":"2026-07-14T17:39:54.857431Z","steps":["trace[868989943] 'process raft request' (duration: 134.836497ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:39:55.227092Z","caller":"traceutil/trace.go:171","msg":"trace[1913337639] transaction","detail":"{read_only:false; response_revision:3841; number_of_response:1; }","duration":"217.7875ms","start":"2026-07-14T17:39:55.009282Z","end":"2026-07-14T17:39:55.22707Z","steps":["trace[1913337639] 'process raft request' (duration: 217.613697ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:06.492689Z","caller":"traceutil/trace.go:171","msg":"trace[1140668357] linearizableReadLoop","detail":"{readStateIndex:4053; appliedIndex:4052; }","duration":"125.403213ms","start":"2026-07-14T17:40:06.367243Z","end":"2026-07-14T17:40:06.492646Z","steps":["trace[1140668357] 'read index received' (duration: 125.197029ms)","trace[1140668357] 'applied index is now lower than readState.Index' (duration: 204.534µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:06.492796Z","caller":"traceutil/trace.go:171","msg":"trace[355057841] transaction","detail":"{read_only:false; response_revision:3904; number_of_response:1; }","duration":"135.232977ms","start":"2026-07-14T17:40:06.357515Z","end":"2026-07-14T17:40:06.492748Z","steps":["trace[355057841] 'process raft request' (duration: 134.960272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:06.492891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.605556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:40:06.493021Z","caller":"traceutil/trace.go:171","msg":"trace[1642237939] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:3904; }","duration":"125.775009ms","start":"2026-07-14T17:40:06.36722Z","end":"2026-07-14T17:40:06.492995Z","steps":["trace[1642237939] 'agreement among raft nodes before linearized reading' (duration: 125.547065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:06.492927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.568405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:40:06.493403Z","caller":"traceutil/trace.go:171","msg":"trace[362053002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3904; }","duration":"126.052455ms","start":"2026-07-14T17:40:06.367313Z","end":"2026-07-14T17:40:06.493366Z","steps":["trace[362053002] 'agreement among raft nodes before linearized reading' (duration: 125.539046ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:19.478076Z","caller":"traceutil/trace.go:171","msg":"trace[59275817] transaction","detail":"{read_only:false; response_revision:3974; number_of_response:1; }","duration":"102.333925ms","start":"2026-07-14T17:40:19.375335Z","end":"2026-07-14T17:40:19.477669Z","steps":["trace[59275817] 'process raft request' (duration: 102.169022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:29.787575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.327504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:29.787711Z","caller":"traceutil/trace.go:171","msg":"trace[62412147] linearizableReadLoop","detail":"{readStateIndex:4213; appliedIndex:4212; }","duration":"128.677907ms","start":"2026-07-14T17:40:29.659014Z","end":"2026-07-14T17:40:29.787692Z","steps":["trace[62412147] 'read index received' (duration: 28.044038ms)","trace[62412147] 'applied index is now lower than readState.Index' (duration: 100.632089ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:29.787818Z","caller":"traceutil/trace.go:171","msg":"trace[405439092] transaction","detail":"{read_only:false; response_revision:4048; number_of_response:1; }","duration":"194.68497ms","start":"2026-07-14T17:40:29.593118Z","end":"2026-07-14T17:40:29.787803Z","steps":["trace[405439092] 'process raft request' (duration: 94.02823ms)","trace[405439092] 'compare' (duration: 100.203842ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:29.788162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.123334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-14T17:40:29.788248Z","caller":"traceutil/trace.go:171","msg":"trace[551392910] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4048; }","duration":"129.257566ms","start":"2026-07-14T17:40:29.658969Z","end":"2026-07-14T17:40:29.788226Z","steps":["trace[551392910] 'agreement among raft nodes before linearized reading' (duration: 129.003572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:29.788236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.88022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-14T17:40:29.788364Z","caller":"traceutil/trace.go:171","msg":"trace[1227086121] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4048; }","duration":"129.041853ms","start":"2026-07-14T17:40:29.659283Z","end":"2026-07-14T17:40:29.788324Z","steps":["trace[1227086121] 'agreement among raft nodes before linearized reading' (duration: 128.722217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:31.016292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.945308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T17:40:31.016433Z","caller":"traceutil/trace.go:171","msg":"trace[836609833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4054; }","duration":"185.123652ms","start":"2026-07-14T17:40:30.831272Z","end":"2026-07-14T17:40:31.016396Z","steps":["trace[836609833] 'range keys from in-memory index tree' (duration: 184.770175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:31.016756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.560507ms","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-07-14T17:40:31.016832Z","caller":"traceutil/trace.go:171","msg":"trace[468043399] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4054; }","duration":"289.67419ms","start":"2026-07-14T17:40:30.72714Z","end":"2026-07-14T17:40:31.016814Z","steps":["trace[468043399] 'range keys from in-memory index tree' (duration: 289.410685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:31.016842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.445425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-14T17:40:31.016939Z","caller":"traceutil/trace.go:171","msg":"trace[452315539] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4054; }","duration":"207.585007ms","start":"2026-07-14T17:40:30.80933Z","end":"2026-07-14T17:40:31.016915Z","steps":["trace[452315539] 'range keys from in-memory index tree' (duration: 207.272532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:41.854134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.634866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:41.854682Z","caller":"traceutil/trace.go:171","msg":"trace[444343591] transaction","detail":"{read_only:false; response_revision:4515; number_of_response:1; }","duration":"212.961192ms","start":"2026-07-14T17:40:41.641678Z","end":"2026-07-14T17:40:41.854639Z","steps":["trace[444343591] 'process raft request' (duration: 67.468092ms)","trace[444343591] 'compare' (duration: 144.166066ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:41.854588Z","caller":"traceutil/trace.go:171","msg":"trace[1948977551] linearizableReadLoop","detail":"{readStateIndex:4685; appliedIndex:4684; }","duration":"205.270465ms","start":"2026-07-14T17:40:41.649301Z","end":"2026-07-14T17:40:41.854571Z","steps":["trace[1948977551] 'read index received' (duration: 59.927407ms)","trace[1948977551] 'applied index is now lower than readState.Index' (duration: 145.341628ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:41.856277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.429226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:18 size:533483"} {"level":"info","ts":"2026-07-14T17:40:41.856441Z","caller":"traceutil/trace.go:171","msg":"trace[1423767359] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:18; response_revision:4515; }","duration":"174.613209ms","start":"2026-07-14T17:40:41.681811Z","end":"2026-07-14T17:40:41.856424Z","steps":["trace[1423767359] 'agreement among raft nodes before linearized reading' (duration: 173.291515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:41.858221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.885819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:2109559"} {"level":"info","ts":"2026-07-14T17:40:41.858274Z","caller":"traceutil/trace.go:171","msg":"trace[1628893228] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4515; }","duration":"208.965141ms","start":"2026-07-14T17:40:41.649296Z","end":"2026-07-14T17:40:41.858261Z","steps":["trace[1628893228] 'agreement among raft nodes before linearized reading' (duration: 205.730333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:42.723841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.779759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-07-14T17:40:42.723973Z","caller":"traceutil/trace.go:171","msg":"trace[401890373] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4546; }","duration":"210.928281ms","start":"2026-07-14T17:40:42.512997Z","end":"2026-07-14T17:40:42.723926Z","steps":["trace[401890373] 'range keys from in-memory index tree' (duration: 210.610576ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:42.724226Z","caller":"traceutil/trace.go:171","msg":"trace[1223652705] transaction","detail":"{read_only:false; response_revision:4547; number_of_response:1; }","duration":"181.5852ms","start":"2026-07-14T17:40:42.542598Z","end":"2026-07-14T17:40:42.724184Z","steps":["trace[1223652705] 'process raft request' (duration: 141.046258ms)","trace[1223652705] 'compare' (duration: 39.975351ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:43.62226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.186439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:43.622587Z","caller":"traceutil/trace.go:171","msg":"trace[2060163249] linearizableReadLoop","detail":"{readStateIndex:4728; appliedIndex:4726; }","duration":"258.175826ms","start":"2026-07-14T17:40:43.364394Z","end":"2026-07-14T17:40:43.62257Z","steps":["trace[2060163249] 'read index received' (duration: 44.792156ms)","trace[2060163249] 'applied index is now lower than readState.Index' (duration: 213.38158ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:43.622683Z","caller":"traceutil/trace.go:171","msg":"trace[2144727287] transaction","detail":"{read_only:false; response_revision:4558; number_of_response:1; }","duration":"258.985031ms","start":"2026-07-14T17:40:43.363686Z","end":"2026-07-14T17:40:43.622671Z","steps":["trace[2144727287] 'process raft request' (duration: 140.304063ms)","trace[2144727287] 'compare' (duration: 117.907104ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:43.622777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.363279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:40:43.622858Z","caller":"traceutil/trace.go:171","msg":"trace[1165847454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4558; }","duration":"258.479532ms","start":"2026-07-14T17:40:43.36436Z","end":"2026-07-14T17:40:43.62284Z","steps":["trace[1165847454] 'agreement among raft nodes before linearized reading' (duration: 258.35225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:43.622893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.395128ms","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-07-14T17:40:43.622972Z","caller":"traceutil/trace.go:171","msg":"trace[1213703656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4558; }","duration":"232.51089ms","start":"2026-07-14T17:40:43.390442Z","end":"2026-07-14T17:40:43.622953Z","steps":["trace[1213703656] 'agreement among raft nodes before linearized reading' (duration: 232.275046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:43.623118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.37485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-53f111eb-e529-4648-bae8-516698563bab\" ","response":"range_response_count:1 size:4067"} {"level":"info","ts":"2026-07-14T17:40:43.623147Z","caller":"traceutil/trace.go:171","msg":"trace[657740333] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-53f111eb-e529-4648-bae8-516698563bab; range_end:; response_count:1; response_revision:4558; }","duration":"191.42235ms","start":"2026-07-14T17:40:43.431717Z","end":"2026-07-14T17:40:43.62314Z","steps":["trace[657740333] 'agreement among raft nodes before linearized reading' (duration: 191.324749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:43.623227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.530408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-38c38aff-5860-4052-a42b-0e31560bd5f8\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-07-14T17:40:43.623269Z","caller":"traceutil/trace.go:171","msg":"trace[1552760952] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-38c38aff-5860-4052-a42b-0e31560bd5f8; range_end:; response_count:1; response_revision:4558; }","duration":"211.591288ms","start":"2026-07-14T17:40:43.411666Z","end":"2026-07-14T17:40:43.623257Z","steps":["trace[1552760952] 'agreement among raft nodes before linearized reading' (duration: 211.489877ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:44.060717Z","caller":"traceutil/trace.go:171","msg":"trace[1902516049] linearizableReadLoop","detail":"{readStateIndex:4730; appliedIndex:4729; }","duration":"202.19353ms","start":"2026-07-14T17:40:43.858499Z","end":"2026-07-14T17:40:44.060693Z","steps":["trace[1902516049] 'read index received' (duration: 201.933005ms)","trace[1902516049] 'applied index is now lower than readState.Index' (duration: 259.655µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:44.060836Z","caller":"traceutil/trace.go:171","msg":"trace[1225396432] transaction","detail":"{read_only:false; response_revision:4560; number_of_response:1; }","duration":"428.868936ms","start":"2026-07-14T17:40:43.631957Z","end":"2026-07-14T17:40:44.060826Z","steps":["trace[1225396432] 'process raft request' (duration: 428.55845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.060961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:43.631936Z","time spent":"428.942477ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3862,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:40:44.061194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.698468ms","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-07-14T17:40:44.061245Z","caller":"traceutil/trace.go:171","msg":"trace[237636292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4560; }","duration":"202.78428ms","start":"2026-07-14T17:40:43.858447Z","end":"2026-07-14T17:40:44.061231Z","steps":["trace[237636292] 'agreement among raft nodes before linearized reading' (duration: 202.643248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:44.061334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.586524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-07-14T17:40:44.06135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.863005ms","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-07-14T17:40:44.061388Z","caller":"traceutil/trace.go:171","msg":"trace[812443608] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4560; }","duration":"114.682756ms","start":"2026-07-14T17:40:43.94669Z","end":"2026-07-14T17:40:44.061373Z","steps":["trace[812443608] 'agreement among raft nodes before linearized reading' (duration: 114.528953ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:44.061424Z","caller":"traceutil/trace.go:171","msg":"trace[66422391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4560; }","duration":"192.990336ms","start":"2026-07-14T17:40:43.868415Z","end":"2026-07-14T17:40:44.061405Z","steps":["trace[66422391] 'agreement among raft nodes before linearized reading' (duration: 192.743712ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:45.70744Z","caller":"traceutil/trace.go:171","msg":"trace[574261953] transaction","detail":"{read_only:false; response_revision:4612; number_of_response:1; }","duration":"176.483108ms","start":"2026-07-14T17:40:45.530926Z","end":"2026-07-14T17:40:45.707409Z","steps":["trace[574261953] 'process raft request' (duration: 176.311535ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:45.91603Z","caller":"traceutil/trace.go:171","msg":"trace[797520673] transaction","detail":"{read_only:false; response_revision:4618; number_of_response:1; }","duration":"184.668802ms","start":"2026-07-14T17:40:45.731321Z","end":"2026-07-14T17:40:45.91599Z","steps":["trace[797520673] 'process raft request' (duration: 184.410207ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:45.916113Z","caller":"traceutil/trace.go:171","msg":"trace[1507293133] transaction","detail":"{read_only:false; response_revision:4617; number_of_response:1; }","duration":"185.546808ms","start":"2026-07-14T17:40:45.730554Z","end":"2026-07-14T17:40:45.9161Z","steps":["trace[1507293133] 'process raft request' (duration: 180.109022ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:51.005632Z","caller":"traceutil/trace.go:171","msg":"trace[1312250276] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"247.443699ms","start":"2026-07-14T17:40:50.758168Z","end":"2026-07-14T17:40:51.005611Z","steps":["trace[1312250276] 'process raft request' (duration: 247.185895ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:51.156372Z","caller":"traceutil/trace.go:171","msg":"trace[2048525602] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"133.678892ms","start":"2026-07-14T17:40:51.022669Z","end":"2026-07-14T17:40:51.156347Z","steps":["trace[2048525602] 'process raft request' (duration: 133.515239ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:51.419585Z","caller":"traceutil/trace.go:171","msg":"trace[1577060374] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"154.147023ms","start":"2026-07-14T17:40:51.265392Z","end":"2026-07-14T17:40:51.419539Z","steps":["trace[1577060374] 'process raft request' (duration: 115.049934ms)","trace[1577060374] 'compare' (duration: 38.708942ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:51.420015Z","caller":"traceutil/trace.go:171","msg":"trace[1548671463] linearizableReadLoop","detail":"{readStateIndex:4851; appliedIndex:4849; }","duration":"111.482492ms","start":"2026-07-14T17:40:51.308451Z","end":"2026-07-14T17:40:51.419933Z","steps":["trace[1548671463] 'read index received' (duration: 71.931305ms)","trace[1548671463] 'applied index is now lower than readState.Index' (duration: 39.549437ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:51.420142Z","caller":"traceutil/trace.go:171","msg":"trace[1410118902] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"148.188428ms","start":"2026-07-14T17:40:51.271913Z","end":"2026-07-14T17:40:51.420102Z","steps":["trace[1410118902] 'process raft request' (duration: 147.509166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:51.420574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.856169ms","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-07-14T17:40:51.420638Z","caller":"traceutil/trace.go:171","msg":"trace[1848929333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4674; }","duration":"112.205175ms","start":"2026-07-14T17:40:51.308413Z","end":"2026-07-14T17:40:51.420619Z","steps":["trace[1848929333] 'agreement among raft nodes before linearized reading' (duration: 111.754177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:53.1921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.594239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:53.19223Z","caller":"traceutil/trace.go:171","msg":"trace[1538082679] linearizableReadLoop","detail":"{readStateIndex:4921; appliedIndex:4920; }","duration":"441.539717ms","start":"2026-07-14T17:40:52.750663Z","end":"2026-07-14T17:40:53.192202Z","steps":["trace[1538082679] 'read index received' (duration: 115.717514ms)","trace[1538082679] 'applied index is now lower than readState.Index' (duration: 325.820983ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:53.192298Z","caller":"traceutil/trace.go:171","msg":"trace[2101586027] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"600.552492ms","start":"2026-07-14T17:40:52.591686Z","end":"2026-07-14T17:40:53.192238Z","steps":["trace[2101586027] 'process raft request' (duration: 274.746159ms)","trace[2101586027] 'compare' (duration: 325.376106ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:53.192443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:52.591622Z","time spent":"600.753516ms","remote":"127.0.0.1:50852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:40:53.192456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.777422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abd59da2-e454-4a21-91af-fa705d3221e6\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-14T17:40:53.192517Z","caller":"traceutil/trace.go:171","msg":"trace[1348870288] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-abd59da2-e454-4a21-91af-fa705d3221e6; range_end:; response_count:1; response_revision:4744; }","duration":"441.892704ms","start":"2026-07-14T17:40:52.750609Z","end":"2026-07-14T17:40:53.192502Z","steps":["trace[1348870288] 'agreement among raft nodes before linearized reading' (duration: 441.66379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:53.192562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:52.750584Z","time spent":"441.968025ms","remote":"127.0.0.1:50972","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-abd59da2-e454-4a21-91af-fa705d3221e6\" "} {"level":"warn","ts":"2026-07-14T17:40:53.467485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.971035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:53.467692Z","caller":"traceutil/trace.go:171","msg":"trace[1506037374] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"190.119697ms","start":"2026-07-14T17:40:53.277539Z","end":"2026-07-14T17:40:53.467659Z","steps":["trace[1506037374] 'process raft request' (duration: 190.018456ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:53.467799Z","caller":"traceutil/trace.go:171","msg":"trace[515188076] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"258.122512ms","start":"2026-07-14T17:40:53.20966Z","end":"2026-07-14T17:40:53.467783Z","steps":["trace[515188076] 'process raft request' (duration: 153.77667ms)","trace[515188076] 'compare' (duration: 103.870443ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:53.544115Z","caller":"traceutil/trace.go:171","msg":"trace[989400660] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"101.250288ms","start":"2026-07-14T17:40:53.442843Z","end":"2026-07-14T17:40:53.544094Z","steps":["trace[989400660] 'process raft request' (duration: 101.153726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:53.544252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.795193ms","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-07-14T17:40:53.544308Z","caller":"traceutil/trace.go:171","msg":"trace[962540323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4748; }","duration":"103.900024ms","start":"2026-07-14T17:40:53.440394Z","end":"2026-07-14T17:40:53.544294Z","steps":["trace[962540323] 'agreement among raft nodes before linearized reading' (duration: 103.705211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:53.544599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.110308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-07-14T17:40:53.544648Z","caller":"traceutil/trace.go:171","msg":"trace[1346356678] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4748; }","duration":"104.174749ms","start":"2026-07-14T17:40:53.440461Z","end":"2026-07-14T17:40:53.544635Z","steps":["trace[1346356678] 'agreement among raft nodes before linearized reading' (duration: 104.047787ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:53.544124Z","caller":"traceutil/trace.go:171","msg":"trace[1119965676] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"118.713415ms","start":"2026-07-14T17:40:53.425372Z","end":"2026-07-14T17:40:53.544086Z","steps":["trace[1119965676] 'process raft request' (duration: 118.395259ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:53.789044Z","caller":"traceutil/trace.go:171","msg":"trace[568421065] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"157.311422ms","start":"2026-07-14T17:40:53.631702Z","end":"2026-07-14T17:40:53.789013Z","steps":["trace[568421065] 'process raft request' (duration: 149.489494ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:53.789292Z","caller":"traceutil/trace.go:171","msg":"trace[966131637] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"155.709983ms","start":"2026-07-14T17:40:53.633572Z","end":"2026-07-14T17:40:53.789282Z","steps":["trace[966131637] 'process raft request' (duration: 155.459479ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:53.789091Z","caller":"traceutil/trace.go:171","msg":"trace[269675236] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"157.247061ms","start":"2026-07-14T17:40:53.631831Z","end":"2026-07-14T17:40:53.789078Z","steps":["trace[269675236] 'process raft request' (duration: 157.149099ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:53.789103Z","caller":"traceutil/trace.go:171","msg":"trace[1991789995] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"157.248581ms","start":"2026-07-14T17:40:53.631829Z","end":"2026-07-14T17:40:53.789078Z","steps":["trace[1991789995] 'process raft request' (duration: 157.076878ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:54.175354Z","caller":"traceutil/trace.go:171","msg":"trace[1990150217] linearizableReadLoop","detail":"{readStateIndex:4933; appliedIndex:4932; }","duration":"386.051215ms","start":"2026-07-14T17:40:53.789263Z","end":"2026-07-14T17:40:54.175314Z","steps":["trace[1990150217] 'read index received' (duration: 317.578304ms)","trace[1990150217] 'applied index is now lower than readState.Index' (duration: 68.471901ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:54.175474Z","caller":"traceutil/trace.go:171","msg":"trace[1683199466] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"444.379608ms","start":"2026-07-14T17:40:53.731084Z","end":"2026-07-14T17:40:54.175463Z","steps":["trace[1683199466] 'process raft request' (duration: 375.743684ms)","trace[1683199466] 'compare' (duration: 68.347669ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:54.175552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.450029ms","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-07-14T17:40:54.175614Z","caller":"traceutil/trace.go:171","msg":"trace[326626937] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:4756; }","duration":"424.559641ms","start":"2026-07-14T17:40:53.751039Z","end":"2026-07-14T17:40:54.175599Z","steps":["trace[326626937] 'agreement among raft nodes before linearized reading' (duration: 424.428478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.175618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:53.731068Z","time spent":"444.43011ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:40:54.175646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:53.751017Z","time spent":"424.621622ms","remote":"127.0.0.1:51142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":26,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:40:54.175881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.051886ms","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-07-14T17:40:54.175906Z","caller":"traceutil/trace.go:171","msg":"trace[1736444020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4756; }","duration":"422.092988ms","start":"2026-07-14T17:40:53.753807Z","end":"2026-07-14T17:40:54.1759Z","steps":["trace[1736444020] 'agreement among raft nodes before linearized reading' (duration: 421.978396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.175926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:53.753799Z","time spent":"422.123508ms","remote":"127.0.0.1:51080","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-07-14T17:40:54.175995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.966073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-07-14T17:40:54.176067Z","caller":"traceutil/trace.go:171","msg":"trace[606413329] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4756; }","duration":"435.095045ms","start":"2026-07-14T17:40:53.740955Z","end":"2026-07-14T17:40:54.17605Z","steps":["trace[606413329] 'agreement among raft nodes before linearized reading' (duration: 434.888862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.176111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:53.740928Z","time spent":"435.166597ms","remote":"127.0.0.1:55384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-07-14T17:40:54.236328Z","caller":"traceutil/trace.go:171","msg":"trace[1183972868] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"440.758764ms","start":"2026-07-14T17:40:53.79553Z","end":"2026-07-14T17:40:54.236288Z","steps":["trace[1183972868] 'process raft request' (duration: 440.375137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.236393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.757088ms","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-07-14T17:40:54.236398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.698992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:40:54.236465Z","caller":"traceutil/trace.go:171","msg":"trace[1021905906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4758; }","duration":"116.86858ms","start":"2026-07-14T17:40:54.11958Z","end":"2026-07-14T17:40:54.236448Z","steps":["trace[1021905906] 'agreement among raft nodes before linearized reading' (duration: 116.640476ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:54.23666Z","caller":"traceutil/trace.go:171","msg":"trace[1784810565] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4758; }","duration":"183.804094ms","start":"2026-07-14T17:40:54.052655Z","end":"2026-07-14T17:40:54.236459Z","steps":["trace[1784810565] 'agreement among raft nodes before linearized reading' (duration: 183.665582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.236398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.292394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-07-14T17:40:54.236712Z","caller":"traceutil/trace.go:171","msg":"trace[672091537] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"258.324111ms","start":"2026-07-14T17:40:53.978058Z","end":"2026-07-14T17:40:54.236382Z","steps":["trace[672091537] 'process raft request' (duration: 258.043296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.236711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:53.795508Z","time spent":"440.907227ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:40:54.236838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.804076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-14T17:40:54.236874Z","caller":"traceutil/trace.go:171","msg":"trace[708737973] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4758; }","duration":"105.865537ms","start":"2026-07-14T17:40:54.130997Z","end":"2026-07-14T17:40:54.236862Z","steps":["trace[708737973] 'agreement among raft nodes before linearized reading' (duration: 105.182685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.236871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.347995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-14T17:40:54.236779Z","caller":"traceutil/trace.go:171","msg":"trace[1934628580] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4758; }","duration":"106.714951ms","start":"2026-07-14T17:40:54.130041Z","end":"2026-07-14T17:40:54.236756Z","steps":["trace[1934628580] 'agreement among raft nodes before linearized reading' (duration: 106.232813ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:54.236957Z","caller":"traceutil/trace.go:171","msg":"trace[1694956730] range","detail":"{range_begin:/registry/masterleases/199.19.213.119; range_end:; response_count:1; response_revision:4758; }","duration":"273.456847ms","start":"2026-07-14T17:40:53.963446Z","end":"2026-07-14T17:40:54.236903Z","steps":["trace[1694956730] 'agreement among raft nodes before linearized reading' (duration: 272.995839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.236938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.124191ms","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-07-14T17:40:54.237086Z","caller":"traceutil/trace.go:171","msg":"trace[351714671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4758; }","duration":"106.334245ms","start":"2026-07-14T17:40:54.13074Z","end":"2026-07-14T17:40:54.237074Z","steps":["trace[351714671] 'agreement among raft nodes before linearized reading' (duration: 105.443509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.866281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.301232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:54.866419Z","caller":"traceutil/trace.go:171","msg":"trace[623361196] linearizableReadLoop","detail":"{readStateIndex:4945; appliedIndex:4944; }","duration":"484.632969ms","start":"2026-07-14T17:40:54.381764Z","end":"2026-07-14T17:40:54.866397Z","steps":["trace[623361196] 'read index received' (duration: 51.411µs)","trace[623361196] 'applied index is now lower than readState.Index' (duration: 484.579288ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:54.866434Z","caller":"traceutil/trace.go:171","msg":"trace[1373557449] transaction","detail":"{read_only:false; response_revision:4767; number_of_response:1; }","duration":"484.679409ms","start":"2026-07-14T17:40:54.381734Z","end":"2026-07-14T17:40:54.866414Z","steps":["trace[1373557449] 'process raft request' (duration: 235.159494ms)","trace[1373557449] 'compare' (duration: 248.874064ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:54.866657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.882034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-07-14T17:40:54.866715Z","caller":"traceutil/trace.go:171","msg":"trace[1341614865] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4767; }","duration":"484.952414ms","start":"2026-07-14T17:40:54.381745Z","end":"2026-07-14T17:40:54.866698Z","steps":["trace[1341614865] 'agreement among raft nodes before linearized reading' (duration: 484.767221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.866699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:54.381715Z","time spent":"484.760672ms","remote":"127.0.0.1:50852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":725,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:40:54.866768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:54.381739Z","time spent":"485.016965ms","remote":"127.0.0.1:50936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1474,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-14T17:40:54.867153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.228794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6dc4cc94d5-nck67.18c238cc84968002\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-07-14T17:40:54.867211Z","caller":"traceutil/trace.go:171","msg":"trace[1680439763] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-6dc4cc94d5-nck67.18c238cc84968002; range_end:; response_count:1; response_revision:4767; }","duration":"418.320776ms","start":"2026-07-14T17:40:54.448876Z","end":"2026-07-14T17:40:54.867197Z","steps":["trace[1680439763] 'agreement among raft nodes before linearized reading' (duration: 418.180444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.867256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:54.448856Z","time spent":"418.384037ms","remote":"127.0.0.1:50852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":919,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6dc4cc94d5-nck67.18c238cc84968002\" "} {"level":"warn","ts":"2026-07-14T17:40:54.867569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.029718ms","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-07-14T17:40:54.867669Z","caller":"traceutil/trace.go:171","msg":"trace[289167146] 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:4767; }","duration":"407.177171ms","start":"2026-07-14T17:40:54.460464Z","end":"2026-07-14T17:40:54.867641Z","steps":["trace[289167146] 'agreement among raft nodes before linearized reading' (duration: 406.905226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:54.86773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:40:54.460442Z","time spent":"407.271662ms","remote":"127.0.0.1:50936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-07-14T17:40:55.716092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.075114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:40:55.716323Z","caller":"traceutil/trace.go:171","msg":"trace[1854614451] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"162.11912ms","start":"2026-07-14T17:40:55.554176Z","end":"2026-07-14T17:40:55.716295Z","steps":["trace[1854614451] 'process raft request' (duration: 53.68915ms)","trace[1854614451] 'compare' (duration: 107.914301ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:55.716471Z","caller":"traceutil/trace.go:171","msg":"trace[1671867742] transaction","detail":"{read_only:false; response_revision:4788; number_of_response:1; }","duration":"161.445948ms","start":"2026-07-14T17:40:55.555003Z","end":"2026-07-14T17:40:55.716449Z","steps":["trace[1671867742] 'process raft request' (duration: 161.211774ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:55.716774Z","caller":"traceutil/trace.go:171","msg":"trace[835810] linearizableReadLoop","detail":"{readStateIndex:4966; appliedIndex:4964; }","duration":"161.311386ms","start":"2026-07-14T17:40:55.555446Z","end":"2026-07-14T17:40:55.716757Z","steps":["trace[835810] 'read index received' (duration: 52.51504ms)","trace[835810] 'applied index is now lower than readState.Index' (duration: 108.795476ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:40:55.716879Z","caller":"traceutil/trace.go:171","msg":"trace[418996571] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"160.95295ms","start":"2026-07-14T17:40:55.555914Z","end":"2026-07-14T17:40:55.716867Z","steps":["trace[418996571] 'process raft request' (duration: 160.793867ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:55.716949Z","caller":"traceutil/trace.go:171","msg":"trace[319909943] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"161.281275ms","start":"2026-07-14T17:40:55.555653Z","end":"2026-07-14T17:40:55.716934Z","steps":["trace[319909943] 'process raft request' (duration: 160.97209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:55.717136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.665072ms","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-07-14T17:40:55.717217Z","caller":"traceutil/trace.go:171","msg":"trace[966750971] 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:4791; }","duration":"161.777424ms","start":"2026-07-14T17:40:55.555424Z","end":"2026-07-14T17:40:55.717201Z","steps":["trace[966750971] 'agreement among raft nodes before linearized reading' (duration: 161.54928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:55.717146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.958837ms","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-07-14T17:40:55.717308Z","caller":"traceutil/trace.go:171","msg":"trace[1456326860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4791; }","duration":"154.13538ms","start":"2026-07-14T17:40:55.56316Z","end":"2026-07-14T17:40:55.717295Z","steps":["trace[1456326860] 'agreement among raft nodes before linearized reading' (duration: 153.908817ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:55.716755Z","caller":"traceutil/trace.go:171","msg":"trace[821240751] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"161.152283ms","start":"2026-07-14T17:40:55.555571Z","end":"2026-07-14T17:40:55.716723Z","steps":["trace[821240751] 'process raft request' (duration: 160.748316ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:57.613565Z","caller":"traceutil/trace.go:171","msg":"trace[123975807] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"128.73824ms","start":"2026-07-14T17:40:57.484772Z","end":"2026-07-14T17:40:57.61351Z","steps":["trace[123975807] 'process raft request' (duration: 128.443655ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:40:57.613579Z","caller":"traceutil/trace.go:171","msg":"trace[1470903598] linearizableReadLoop","detail":"{readStateIndex:4984; appliedIndex:4983; }","duration":"125.402162ms","start":"2026-07-14T17:40:57.487979Z","end":"2026-07-14T17:40:57.613381Z","steps":["trace[1470903598] 'read index received' (duration: 125.121137ms)","trace[1470903598] 'applied index is now lower than readState.Index' (duration: 279.825µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:40:57.614302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.341418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T17:40:57.614348Z","caller":"traceutil/trace.go:171","msg":"trace[1592543427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4806; }","duration":"126.432189ms","start":"2026-07-14T17:40:57.487904Z","end":"2026-07-14T17:40:57.614336Z","steps":["trace[1592543427] 'agreement among raft nodes before linearized reading' (duration: 126.259316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:40:57.61443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.772748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abd59da2-e454-4a21-91af-fa705d3221e6\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-07-14T17:40:57.614518Z","caller":"traceutil/trace.go:171","msg":"trace[1933958007] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-abd59da2-e454-4a21-91af-fa705d3221e6; range_end:; response_count:1; response_revision:4806; }","duration":"125.910121ms","start":"2026-07-14T17:40:57.488583Z","end":"2026-07-14T17:40:57.614494Z","steps":["trace[1933958007] 'agreement among raft nodes before linearized reading' (duration: 125.647216ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:06.527047Z","caller":"traceutil/trace.go:171","msg":"trace[1144553346] linearizableReadLoop","detail":"{readStateIndex:5093; appliedIndex:5092; }","duration":"156.298866ms","start":"2026-07-14T17:41:06.370723Z","end":"2026-07-14T17:41:06.527022Z","steps":["trace[1144553346] 'read index received' (duration: 156.108433ms)","trace[1144553346] 'applied index is now lower than readState.Index' (duration: 189.553µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:06.5272Z","caller":"traceutil/trace.go:171","msg":"trace[1362116418] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"205.274064ms","start":"2026-07-14T17:41:06.321912Z","end":"2026-07-14T17:41:06.527186Z","steps":["trace[1362116418] 'process raft request' (duration: 204.969118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:06.527411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.672022ms","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-07-14T17:41:06.527446Z","caller":"traceutil/trace.go:171","msg":"trace[258670394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4910; }","duration":"156.749744ms","start":"2026-07-14T17:41:06.370683Z","end":"2026-07-14T17:41:06.527433Z","steps":["trace[258670394] 'agreement among raft nodes before linearized reading' (duration: 156.625882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:06.52757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.781864ms","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-07-14T17:41:06.527594Z","caller":"traceutil/trace.go:171","msg":"trace[35652926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4910; }","duration":"156.821125ms","start":"2026-07-14T17:41:06.370766Z","end":"2026-07-14T17:41:06.527587Z","steps":["trace[35652926] 'agreement among raft nodes before linearized reading' (duration: 156.747663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:06.527728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.080295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-14T17:41:06.527755Z","caller":"traceutil/trace.go:171","msg":"trace[1495894992] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4910; }","duration":"155.154576ms","start":"2026-07-14T17:41:06.37259Z","end":"2026-07-14T17:41:06.527745Z","steps":["trace[1495894992] 'agreement among raft nodes before linearized reading' (duration: 155.088845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:06.527935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.222264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-14T17:41:06.528034Z","caller":"traceutil/trace.go:171","msg":"trace[439368050] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4910; }","duration":"156.351147ms","start":"2026-07-14T17:41:06.371663Z","end":"2026-07-14T17:41:06.528014Z","steps":["trace[439368050] 'agreement among raft nodes before linearized reading' (duration: 156.166894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.864428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072284316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:13.864518Z","caller":"traceutil/trace.go:171","msg":"trace[1531755419] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4953; }","duration":"1.072419858s","start":"2026-07-14T17:41:12.792077Z","end":"2026-07-14T17:41:13.864496Z","steps":["trace[1531755419] 'count revisions from in-memory index tree' (duration: 1.072153284s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.864564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.792064Z","time spent":"1.072487409s","remote":"127.0.0.1:48858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:41:13.864654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.260086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:13.864744Z","caller":"traceutil/trace.go:171","msg":"trace[1054210428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4953; }","duration":"498.373067ms","start":"2026-07-14T17:41:13.366352Z","end":"2026-07-14T17:41:13.864725Z","steps":["trace[1054210428] 'range keys from in-memory index tree' (duration: 498.198945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.864784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.36634Z","time spent":"498.435529ms","remote":"127.0.0.1:50768","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-07-14T17:41:13.865089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.055385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:41:13.865136Z","caller":"traceutil/trace.go:171","msg":"trace[1118976385] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:4953; }","duration":"422.143347ms","start":"2026-07-14T17:41:13.442982Z","end":"2026-07-14T17:41:13.865126Z","steps":["trace[1118976385] 'count revisions from in-memory index tree' (duration: 421.972994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.86517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.442963Z","time spent":"422.197188ms","remote":"127.0.0.1:37702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:41:13.865297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281538537s","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-07-14T17:41:13.865342Z","caller":"traceutil/trace.go:171","msg":"trace[671009362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4953; }","duration":"1.281584218s","start":"2026-07-14T17:41:12.583747Z","end":"2026-07-14T17:41:13.865331Z","steps":["trace[671009362] 'range keys from in-memory index tree' (duration: 1.281330773s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.865378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.583656Z","time spent":"1.281709399s","remote":"127.0.0.1:51080","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-07-14T17:41:13.865695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26254573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-07-14T17:41:13.865737Z","caller":"traceutil/trace.go:171","msg":"trace[797829085] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4953; }","duration":"1.262620131s","start":"2026-07-14T17:41:12.603105Z","end":"2026-07-14T17:41:13.865725Z","steps":["trace[797829085] 'range keys from in-memory index tree' (duration: 1.262434668s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.865765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.603085Z","time spent":"1.262672882s","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-14T17:41:13.866063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21956645s","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-07-14T17:41:13.866117Z","caller":"traceutil/trace.go:171","msg":"trace[2047774557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4953; }","duration":"1.219644012s","start":"2026-07-14T17:41:12.64646Z","end":"2026-07-14T17:41:13.866104Z","steps":["trace[2047774557] 'range keys from in-memory index tree' (duration: 1.219466599s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.866123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217278621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-07-14T17:41:13.866126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17475927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-14T17:41:13.86677Z","caller":"traceutil/trace.go:171","msg":"trace[1942444796] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4953; }","duration":"1.17540646s","start":"2026-07-14T17:41:12.691315Z","end":"2026-07-14T17:41:13.866721Z","steps":["trace[1942444796] 'range keys from in-memory index tree' (duration: 1.174585906s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.866827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.6913Z","time spent":"1.175511992s","remote":"127.0.0.1:50936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-14T17:41:13.866153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.646448Z","time spent":"1.219694963s","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T17:41:13.86616Z","caller":"traceutil/trace.go:171","msg":"trace[869800036] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4953; }","duration":"1.217336552s","start":"2026-07-14T17:41:12.648812Z","end":"2026-07-14T17:41:13.866148Z","steps":["trace[869800036] 'range keys from in-memory index tree' (duration: 1.217178239s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.867752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.648801Z","time spent":"1.21893194s","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T17:41:13.86616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.77402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:112851"} {"level":"info","ts":"2026-07-14T17:41:13.868087Z","caller":"traceutil/trace.go:171","msg":"trace[123127210] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4953; }","duration":"544.765795ms","start":"2026-07-14T17:41:13.323307Z","end":"2026-07-14T17:41:13.868072Z","steps":["trace[123127210] 'range keys from in-memory index tree' (duration: 542.391504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.866196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219031311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-07-14T17:41:13.868127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.323287Z","time spent":"544.829865ms","remote":"127.0.0.1:50832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":112874,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-07-14T17:41:13.868144Z","caller":"traceutil/trace.go:171","msg":"trace[660282832] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4953; }","duration":"1.221001165s","start":"2026-07-14T17:41:12.647122Z","end":"2026-07-14T17:41:13.868123Z","steps":["trace[660282832] 'range keys from in-memory index tree' (duration: 1.21892892s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.868199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.647105Z","time spent":"1.221080787s","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T17:41:13.866277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172757745s","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-07-14T17:41:13.868303Z","caller":"traceutil/trace.go:171","msg":"trace[1858762454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4953; }","duration":"1.17477326s","start":"2026-07-14T17:41:12.693492Z","end":"2026-07-14T17:41:13.868265Z","steps":["trace[1858762454] 'range keys from in-memory index tree' (duration: 1.172668823s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.866442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.934755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T17:41:13.868465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:12.693478Z","time spent":"1.174944703s","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T17:41:13.868563Z","caller":"traceutil/trace.go:171","msg":"trace[575710558] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:4953; }","duration":"854.050122ms","start":"2026-07-14T17:41:13.014451Z","end":"2026-07-14T17:41:13.868501Z","steps":["trace[575710558] 'count revisions from in-memory index tree' (duration: 851.760232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.866494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.599892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-14T17:41:13.868678Z","caller":"traceutil/trace.go:171","msg":"trace[1791001836] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:4953; }","duration":"546.83311ms","start":"2026-07-14T17:41:13.321834Z","end":"2026-07-14T17:41:13.868667Z","steps":["trace[1791001836] 'range keys from in-memory index tree' (duration: 544.46582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:13.868759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.321807Z","time spent":"546.939272ms","remote":"127.0.0.1:50842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-07-14T17:41:13.869047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:13.014425Z","time spent":"854.596572ms","remote":"127.0.0.1:51146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":26,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-07-14T17:41:14.131333Z","caller":"traceutil/trace.go:171","msg":"trace[937717067] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"210.208382ms","start":"2026-07-14T17:41:13.921102Z","end":"2026-07-14T17:41:14.13131Z","steps":["trace[937717067] 'process raft request' (duration: 210.164621ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:14.13138Z","caller":"traceutil/trace.go:171","msg":"trace[476086762] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"259.019591ms","start":"2026-07-14T17:41:13.872329Z","end":"2026-07-14T17:41:14.131348Z","steps":["trace[476086762] 'process raft request' (duration: 258.812597ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:14.131421Z","caller":"traceutil/trace.go:171","msg":"trace[733379626] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"259.698823ms","start":"2026-07-14T17:41:13.8717Z","end":"2026-07-14T17:41:14.131398Z","steps":["trace[733379626] 'process raft request' (duration: 201.286839ms)","trace[733379626] 'compare' (duration: 57.881185ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:14.131438Z","caller":"traceutil/trace.go:171","msg":"trace[2057889794] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"259.142383ms","start":"2026-07-14T17:41:13.872279Z","end":"2026-07-14T17:41:14.131421Z","steps":["trace[2057889794] 'process raft request' (duration: 258.821968ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:14.131435Z","caller":"traceutil/trace.go:171","msg":"trace[1578084095] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"259.657382ms","start":"2026-07-14T17:41:13.871757Z","end":"2026-07-14T17:41:14.131414Z","steps":["trace[1578084095] 'process raft request' (duration: 259.280995ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:14.131539Z","caller":"traceutil/trace.go:171","msg":"trace[514239004] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"255.653333ms","start":"2026-07-14T17:41:13.875876Z","end":"2026-07-14T17:41:14.13153Z","steps":["trace[514239004] 'process raft request' (duration: 255.330127ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:14.131551Z","caller":"traceutil/trace.go:171","msg":"trace[856375800] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"258.775686ms","start":"2026-07-14T17:41:13.872762Z","end":"2026-07-14T17:41:14.131538Z","steps":["trace[856375800] 'process raft request' (duration: 258.40837ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:14.131582Z","caller":"traceutil/trace.go:171","msg":"trace[2142539434] linearizableReadLoop","detail":"{readStateIndex:5144; appliedIndex:5138; }","duration":"222.621194ms","start":"2026-07-14T17:41:13.908936Z","end":"2026-07-14T17:41:14.131558Z","steps":["trace[2142539434] 'read index received' (duration: 164.079399ms)","trace[2142539434] 'applied index is now lower than readState.Index' (duration: 58.540205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:14.131829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.062712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-14T17:41:14.131832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.990485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-abd59da2-e454-4a21-91af-fa705d3221e6\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-14T17:41:14.131876Z","caller":"traceutil/trace.go:171","msg":"trace[1062325238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4961; }","duration":"170.156073ms","start":"2026-07-14T17:41:13.961707Z","end":"2026-07-14T17:41:14.131863Z","steps":["trace[1062325238] 'agreement among raft nodes before linearized reading' (duration: 169.98548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:14.131885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.035046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:37 size:55823"} {"level":"info","ts":"2026-07-14T17:41:14.131885Z","caller":"traceutil/trace.go:171","msg":"trace[255474190] range","detail":"{range_begin:/registry/persistentvolumes/pvc-abd59da2-e454-4a21-91af-fa705d3221e6; range_end:; response_count:1; response_revision:4961; }","duration":"261.082246ms","start":"2026-07-14T17:41:13.870789Z","end":"2026-07-14T17:41:14.131872Z","steps":["trace[255474190] 'agreement among raft nodes before linearized reading' (duration: 260.890472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:14.131886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.051776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:37 size:55823"} {"level":"info","ts":"2026-07-14T17:41:14.131922Z","caller":"traceutil/trace.go:171","msg":"trace[315967542] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:37; response_revision:4961; }","duration":"261.081716ms","start":"2026-07-14T17:41:13.870829Z","end":"2026-07-14T17:41:14.131911Z","steps":["trace[315967542] 'agreement among raft nodes before linearized reading' (duration: 260.740021ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:14.13193Z","caller":"traceutil/trace.go:171","msg":"trace[838771716] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:37; response_revision:4961; }","duration":"261.112707ms","start":"2026-07-14T17:41:13.870807Z","end":"2026-07-14T17:41:14.13192Z","steps":["trace[838771716] 'agreement among raft nodes before linearized reading' (duration: 260.814041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:14.131999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.717042ms","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-07-14T17:41:14.132022Z","caller":"traceutil/trace.go:171","msg":"trace[266114297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4961; }","duration":"117.770902ms","start":"2026-07-14T17:41:14.014244Z","end":"2026-07-14T17:41:14.132015Z","steps":["trace[266114297] 'agreement among raft nodes before linearized reading' (duration: 117.66748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:14.131879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.360064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:41:14.132073Z","caller":"traceutil/trace.go:171","msg":"trace[369198740] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:4961; }","duration":"114.588718ms","start":"2026-07-14T17:41:14.017475Z","end":"2026-07-14T17:41:14.132064Z","steps":["trace[369198740] 'agreement among raft nodes before linearized reading' (duration: 114.356984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:14.13214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.866313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-14T17:41:14.132175Z","caller":"traceutil/trace.go:171","msg":"trace[2010657371] range","detail":"{range_begin:/registry/masterleases/199.19.213.119; range_end:; response_count:1; response_revision:4961; }","duration":"167.923355ms","start":"2026-07-14T17:41:13.964242Z","end":"2026-07-14T17:41:14.132165Z","steps":["trace[2010657371] 'agreement among raft nodes before linearized reading' (duration: 167.818523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:14.445908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.489118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:41:14.446056Z","caller":"traceutil/trace.go:171","msg":"trace[2003685194] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"225.083135ms","start":"2026-07-14T17:41:14.220945Z","end":"2026-07-14T17:41:14.446028Z","steps":["trace[2003685194] 'process raft request' (duration: 40.366233ms)","trace[2003685194] 'compare' (duration: 184.105232ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:15.92389Z","caller":"traceutil/trace.go:171","msg":"trace[699332168] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"249.011873ms","start":"2026-07-14T17:41:15.674836Z","end":"2026-07-14T17:41:15.923848Z","steps":["trace[699332168] 'process raft request' (duration: 248.8554ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:16.141345Z","caller":"traceutil/trace.go:171","msg":"trace[1636172028] linearizableReadLoop","detail":"{readStateIndex:5159; appliedIndex:5156; }","duration":"212.584265ms","start":"2026-07-14T17:41:15.928739Z","end":"2026-07-14T17:41:16.141323Z","steps":["trace[1636172028] 'read index received' (duration: 189.251955ms)","trace[1636172028] 'applied index is now lower than readState.Index' (duration: 23.33139ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:16.141394Z","caller":"traceutil/trace.go:171","msg":"trace[433252199] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"464.262844ms","start":"2026-07-14T17:41:15.677102Z","end":"2026-07-14T17:41:16.141365Z","steps":["trace[433252199] 'process raft request' (duration: 464.141942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:16.141541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:15.677084Z","time spent":"464.378815ms","remote":"127.0.0.1:51116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1621,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:16.141572Z","caller":"traceutil/trace.go:171","msg":"trace[1227127357] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"226.133247ms","start":"2026-07-14T17:41:15.91543Z","end":"2026-07-14T17:41:16.141564Z","steps":["trace[1227127357] 'process raft request' (duration: 225.847002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:16.141798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.044284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus.18c238c82a053276\" ","response":"range_response_count:1 size:745"} {"level":"info","ts":"2026-07-14T17:41:16.141825Z","caller":"traceutil/trace.go:171","msg":"trace[1038907372] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus.18c238c82a053276; range_end:; response_count:1; response_revision:4974; }","duration":"213.128004ms","start":"2026-07-14T17:41:15.92869Z","end":"2026-07-14T17:41:16.141818Z","steps":["trace[1038907372] 'agreement among raft nodes before linearized reading' (duration: 213.015242ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:16.141885Z","caller":"traceutil/trace.go:171","msg":"trace[1007782537] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"465.644567ms","start":"2026-07-14T17:41:15.676227Z","end":"2026-07-14T17:41:16.141872Z","steps":["trace[1007782537] 'process raft request' (duration: 441.827129ms)","trace[1007782537] 'compare' (duration: 22.871962ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:16.141941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:41:15.676217Z","time spent":"465.695428ms","remote":"127.0.0.1:51116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1413,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:41:16.450062Z","caller":"traceutil/trace.go:171","msg":"trace[2037863508] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"235.984936ms","start":"2026-07-14T17:41:16.214049Z","end":"2026-07-14T17:41:16.450034Z","steps":["trace[2037863508] 'process raft request' (duration: 215.109198ms)","trace[2037863508] 'compare' (duration: 20.710585ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:16.450341Z","caller":"traceutil/trace.go:171","msg":"trace[795013645] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"235.950195ms","start":"2026-07-14T17:41:16.214376Z","end":"2026-07-14T17:41:16.450326Z","steps":["trace[795013645] 'process raft request' (duration: 235.869373ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:16.450423Z","caller":"traceutil/trace.go:171","msg":"trace[981382911] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"236.23201ms","start":"2026-07-14T17:41:16.214165Z","end":"2026-07-14T17:41:16.450397Z","steps":["trace[981382911] 'process raft request' (duration: 235.830933ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:16.450561Z","caller":"traceutil/trace.go:171","msg":"trace[1218977850] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"236.229899ms","start":"2026-07-14T17:41:16.214321Z","end":"2026-07-14T17:41:16.45055Z","steps":["trace[1218977850] 'process raft request' (duration: 235.823132ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:16.450669Z","caller":"traceutil/trace.go:171","msg":"trace[1213763214] linearizableReadLoop","detail":"{readStateIndex:5168; appliedIndex:5162; }","duration":"226.588054ms","start":"2026-07-14T17:41:16.224068Z","end":"2026-07-14T17:41:16.450656Z","steps":["trace[1213763214] 'read index received' (duration: 205.103086ms)","trace[1213763214] 'applied index is now lower than readState.Index' (duration: 21.484218ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:16.4507Z","caller":"traceutil/trace.go:171","msg":"trace[831009545] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"236.327491ms","start":"2026-07-14T17:41:16.214362Z","end":"2026-07-14T17:41:16.45069Z","steps":["trace[831009545] 'process raft request' (duration: 235.846153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:16.450977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.291359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-14T17:41:16.450994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.91066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T17:41:16.45102Z","caller":"traceutil/trace.go:171","msg":"trace[482321058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4983; }","duration":"226.36266ms","start":"2026-07-14T17:41:16.224647Z","end":"2026-07-14T17:41:16.45101Z","steps":["trace[482321058] 'agreement among raft nodes before linearized reading' (duration: 226.203928ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:16.451208Z","caller":"traceutil/trace.go:171","msg":"trace[510748579] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"236.496954ms","start":"2026-07-14T17:41:16.214701Z","end":"2026-07-14T17:41:16.451198Z","steps":["trace[510748579] 'process raft request' (duration: 235.574638ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:16.451997Z","caller":"traceutil/trace.go:171","msg":"trace[595563276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4983; }","duration":"227.015921ms","start":"2026-07-14T17:41:16.224018Z","end":"2026-07-14T17:41:16.451033Z","steps":["trace[595563276] 'agreement among raft nodes before linearized reading' (duration: 226.853498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:26.709059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.38434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:41:26.709251Z","caller":"traceutil/trace.go:171","msg":"trace[846360792] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"156.541798ms","start":"2026-07-14T17:41:26.552689Z","end":"2026-07-14T17:41:26.70923Z","steps":["trace[846360792] 'process raft request' (duration: 156.473017ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:26.709555Z","caller":"traceutil/trace.go:171","msg":"trace[1184170861] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"156.914295ms","start":"2026-07-14T17:41:26.552597Z","end":"2026-07-14T17:41:26.709512Z","steps":["trace[1184170861] 'process raft request' (duration: 47.975655ms)","trace[1184170861] 'compare' (duration: 108.238938ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:44.827309Z","caller":"traceutil/trace.go:171","msg":"trace[1038886477] linearizableReadLoop","detail":"{readStateIndex:5441; appliedIndex:5440; }","duration":"165.66212ms","start":"2026-07-14T17:41:44.661622Z","end":"2026-07-14T17:41:44.827284Z","steps":["trace[1038886477] 'read index received' (duration: 136.278229ms)","trace[1038886477] 'applied index is now lower than readState.Index' (duration: 29.383071ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:44.827381Z","caller":"traceutil/trace.go:171","msg":"trace[705430902] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"174.806033ms","start":"2026-07-14T17:41:44.652543Z","end":"2026-07-14T17:41:44.827349Z","steps":["trace[705430902] 'process raft request' (duration: 145.423092ms)","trace[705430902] 'compare' (duration: 29.233439ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:44.827682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.736802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-14T17:41:44.828121Z","caller":"traceutil/trace.go:171","msg":"trace[912472994] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5249; }","duration":"166.19202ms","start":"2026-07-14T17:41:44.661904Z","end":"2026-07-14T17:41:44.828096Z","steps":["trace[912472994] 'agreement among raft nodes before linearized reading' (duration: 165.62737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:44.827732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.106529ms","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-07-14T17:41:44.828864Z","caller":"traceutil/trace.go:171","msg":"trace[494899560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5249; }","duration":"167.267617ms","start":"2026-07-14T17:41:44.66157Z","end":"2026-07-14T17:41:44.828838Z","steps":["trace[494899560] 'agreement among raft nodes before linearized reading' (duration: 165.822863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:44.828001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.864527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-14T17:41:44.829008Z","caller":"traceutil/trace.go:171","msg":"trace[1648262508] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5249; }","duration":"162.930925ms","start":"2026-07-14T17:41:44.666052Z","end":"2026-07-14T17:41:44.828983Z","steps":["trace[1648262508] 'agreement among raft nodes before linearized reading' (duration: 161.701784ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:44.980308Z","caller":"traceutil/trace.go:171","msg":"trace[2037915779] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"148.28384ms","start":"2026-07-14T17:41:44.831995Z","end":"2026-07-14T17:41:44.980279Z","steps":["trace[2037915779] 'process raft request' (duration: 115.047755ms)","trace[2037915779] 'compare' (duration: 33.059793ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:44.980789Z","caller":"traceutil/trace.go:171","msg":"trace[1623037712] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"148.690236ms","start":"2026-07-14T17:41:44.832071Z","end":"2026-07-14T17:41:44.980761Z","steps":["trace[1623037712] 'process raft request' (duration: 148.168248ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:44.98097Z","caller":"traceutil/trace.go:171","msg":"trace[487310202] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"148.554615ms","start":"2026-07-14T17:41:44.832392Z","end":"2026-07-14T17:41:44.980947Z","steps":["trace[487310202] 'process raft request' (duration: 148.071387ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:44.981154Z","caller":"traceutil/trace.go:171","msg":"trace[848910053] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"148.504023ms","start":"2026-07-14T17:41:44.832547Z","end":"2026-07-14T17:41:44.981051Z","steps":["trace[848910053] 'process raft request' (duration: 148.31513ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:53.025001Z","caller":"traceutil/trace.go:171","msg":"trace[1990185701] linearizableReadLoop","detail":"{readStateIndex:5488; appliedIndex:5487; }","duration":"254.909746ms","start":"2026-07-14T17:41:52.770074Z","end":"2026-07-14T17:41:53.024984Z","steps":["trace[1990185701] 'read index received' (duration: 178.13777ms)","trace[1990185701] 'applied index is now lower than readState.Index' (duration: 76.762996ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:41:53.025146Z","caller":"traceutil/trace.go:171","msg":"trace[1280963905] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"292.43435ms","start":"2026-07-14T17:41:52.73262Z","end":"2026-07-14T17:41:53.025054Z","steps":["trace[1280963905] 'process raft request' (duration: 215.704315ms)","trace[1280963905] 'compare' (duration: 76.578743ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:41:53.025522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.627517ms","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-07-14T17:41:53.025563Z","caller":"traceutil/trace.go:171","msg":"trace[505062402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5294; }","duration":"122.705718ms","start":"2026-07-14T17:41:52.902847Z","end":"2026-07-14T17:41:53.025553Z","steps":["trace[505062402] 'agreement among raft nodes before linearized reading' (duration: 122.593626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:53.025644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.543894ms","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-07-14T17:41:53.025717Z","caller":"traceutil/trace.go:171","msg":"trace[1364102061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5294; }","duration":"149.652387ms","start":"2026-07-14T17:41:52.876049Z","end":"2026-07-14T17:41:53.025701Z","steps":["trace[1364102061] 'agreement among raft nodes before linearized reading' (duration: 149.475024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:53.025253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.164031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-14T17:41:53.026044Z","caller":"traceutil/trace.go:171","msg":"trace[625799971] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5294; }","duration":"255.984484ms","start":"2026-07-14T17:41:52.770043Z","end":"2026-07-14T17:41:53.026027Z","steps":["trace[625799971] 'agreement among raft nodes before linearized reading' (duration: 255.11602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:53.026209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.060759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2362294"} {"level":"info","ts":"2026-07-14T17:41:53.026294Z","caller":"traceutil/trace.go:171","msg":"trace[996038802] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5294; }","duration":"240.172971ms","start":"2026-07-14T17:41:52.786106Z","end":"2026-07-14T17:41:53.026279Z","steps":["trace[996038802] 'agreement among raft nodes before linearized reading' (duration: 239.215095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:41:53.231002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.252999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-07-14T17:41:53.231077Z","caller":"traceutil/trace.go:171","msg":"trace[2058171698] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5300; }","duration":"105.346801ms","start":"2026-07-14T17:41:53.125711Z","end":"2026-07-14T17:41:53.231058Z","steps":["trace[2058171698] 'range keys from in-memory index tree' (duration: 104.790691ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:41:53.44104Z","caller":"traceutil/trace.go:171","msg":"trace[77810066] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"174.404667ms","start":"2026-07-14T17:41:53.266553Z","end":"2026-07-14T17:41:53.440958Z","steps":["trace[77810066] 'process raft request' (duration: 174.230294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:04.191582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.582361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-14T17:42:04.191685Z","caller":"traceutil/trace.go:171","msg":"trace[926970748] range","detail":"{range_begin:/registry/masterleases/199.19.213.119; range_end:; response_count:1; response_revision:5379; }","duration":"224.728154ms","start":"2026-07-14T17:42:03.966929Z","end":"2026-07-14T17:42:04.191657Z","steps":["trace[926970748] 'range keys from in-memory index tree' (duration: 224.354648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:04.191977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.293564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-db-init-6x465\" ","response":"range_response_count:1 size:7347"} {"level":"info","ts":"2026-07-14T17:42:04.192055Z","caller":"traceutil/trace.go:171","msg":"trace[1690298329] range","detail":"{range_begin:/registry/pods/openstack/keystone-db-init-6x465; range_end:; response_count:1; response_revision:5379; }","duration":"245.431185ms","start":"2026-07-14T17:42:03.946605Z","end":"2026-07-14T17:42:04.192036Z","steps":["trace[1690298329] 'range keys from in-memory index tree' (duration: 245.131441ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:04.414165Z","caller":"traceutil/trace.go:171","msg":"trace[202021673] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"187.787065ms","start":"2026-07-14T17:42:04.226345Z","end":"2026-07-14T17:42:04.414132Z","steps":["trace[202021673] 'process raft request' (duration: 89.943332ms)","trace[202021673] 'compare' (duration: 97.338704ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:42:10.988706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2820} {"level":"info","ts":"2026-07-14T17:42:11.090911Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2820,"took":"100.449628ms","hash":3811296932,"current-db-size-bytes":52834304,"current-db-size":"53 MB","current-db-size-in-use-bytes":29003776,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-14T17:42:11.090968Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3811296932,"revision":2820,"compact-revision":-1} {"level":"info","ts":"2026-07-14T17:42:26.645652Z","caller":"traceutil/trace.go:171","msg":"trace[584749018] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"109.844735ms","start":"2026-07-14T17:42:26.535785Z","end":"2026-07-14T17:42:26.64563Z","steps":["trace[584749018] 'process raft request' (duration: 109.707793ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:31.562599Z","caller":"traceutil/trace.go:171","msg":"trace[1975873910] linearizableReadLoop","detail":"{readStateIndex:5780; appliedIndex:5778; }","duration":"266.5982ms","start":"2026-07-14T17:42:31.295977Z","end":"2026-07-14T17:42:31.562575Z","steps":["trace[1975873910] 'read index received' (duration: 88.535925ms)","trace[1975873910] 'applied index is now lower than readState.Index' (duration: 178.061035ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:42:31.562726Z","caller":"traceutil/trace.go:171","msg":"trace[1395743410] transaction","detail":"{read_only:false; response_revision:5577; number_of_response:1; }","duration":"276.41211ms","start":"2026-07-14T17:42:31.286302Z","end":"2026-07-14T17:42:31.562714Z","steps":["trace[1395743410] 'process raft request' (duration: 276.136445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:31.562968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.492606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-07-14T17:42:31.563304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.346002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:42:31.563361Z","caller":"traceutil/trace.go:171","msg":"trace[661512063] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:5577; }","duration":"259.675758ms","start":"2026-07-14T17:42:31.303668Z","end":"2026-07-14T17:42:31.563344Z","steps":["trace[661512063] 'agreement among raft nodes before linearized reading' (duration: 259.328012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:31.563161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.883349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-14T17:42:31.563617Z","caller":"traceutil/trace.go:171","msg":"trace[636080683] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5577; }","duration":"258.354006ms","start":"2026-07-14T17:42:31.305252Z","end":"2026-07-14T17:42:31.563606Z","steps":["trace[636080683] 'agreement among raft nodes before linearized reading' (duration: 257.796187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:31.563111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.485314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:42:31.563696Z","caller":"traceutil/trace.go:171","msg":"trace[1157712386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5577; }","duration":"197.153384ms","start":"2026-07-14T17:42:31.366525Z","end":"2026-07-14T17:42:31.563678Z","steps":["trace[1157712386] 'agreement among raft nodes before linearized reading' (duration: 196.430993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:31.563134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.096108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T17:42:31.563997Z","caller":"traceutil/trace.go:171","msg":"trace[1006007408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5577; }","duration":"268.085374ms","start":"2026-07-14T17:42:31.2959Z","end":"2026-07-14T17:42:31.563985Z","steps":["trace[1006007408] 'agreement among raft nodes before linearized reading' (duration: 267.005897ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:31.563327Z","caller":"traceutil/trace.go:171","msg":"trace[1705615619] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5577; }","duration":"264.888622ms","start":"2026-07-14T17:42:31.298421Z","end":"2026-07-14T17:42:31.563309Z","steps":["trace[1705615619] 'agreement among raft nodes before linearized reading' (duration: 264.371834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:31.563275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.548395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-14T17:42:31.563223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.105037ms","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-07-14T17:42:31.564305Z","caller":"traceutil/trace.go:171","msg":"trace[1023600901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5577; }","duration":"208.243884ms","start":"2026-07-14T17:42:31.356049Z","end":"2026-07-14T17:42:31.564293Z","steps":["trace[1023600901] 'agreement among raft nodes before linearized reading' (duration: 206.789861ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:31.564447Z","caller":"traceutil/trace.go:171","msg":"trace[740696631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5577; }","duration":"263.726494ms","start":"2026-07-14T17:42:31.300706Z","end":"2026-07-14T17:42:31.564433Z","steps":["trace[740696631] 'agreement among raft nodes before linearized reading' (duration: 262.493174ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:47.803761Z","caller":"traceutil/trace.go:171","msg":"trace[1920731589] transaction","detail":"{read_only:false; response_revision:5711; number_of_response:1; }","duration":"144.948259ms","start":"2026-07-14T17:42:47.658748Z","end":"2026-07-14T17:42:47.803696Z","steps":["trace[1920731589] 'process raft request' (duration: 115.885732ms)","trace[1920731589] 'compare' (duration: 28.851313ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:42:47.828467Z","caller":"traceutil/trace.go:171","msg":"trace[1572343507] linearizableReadLoop","detail":"{readStateIndex:5918; appliedIndex:5916; }","duration":"169.217829ms","start":"2026-07-14T17:42:47.659214Z","end":"2026-07-14T17:42:47.828432Z","steps":["trace[1572343507] 'read index received' (duration: 115.550837ms)","trace[1572343507] 'applied index is now lower than readState.Index' (duration: 53.665612ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:42:47.828584Z","caller":"traceutil/trace.go:171","msg":"trace[1506110397] transaction","detail":"{read_only:false; response_revision:5712; number_of_response:1; }","duration":"169.443672ms","start":"2026-07-14T17:42:47.659117Z","end":"2026-07-14T17:42:47.82856Z","steps":["trace[1506110397] 'process raft request' (duration: 169.153928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:47.828739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.491364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T17:42:47.828787Z","caller":"traceutil/trace.go:171","msg":"trace[222195383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5712; }","duration":"169.572225ms","start":"2026-07-14T17:42:47.659197Z","end":"2026-07-14T17:42:47.828769Z","steps":["trace[222195383] 'agreement among raft nodes before linearized reading' (duration: 169.398062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:42:47.828838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.668164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-07-14T17:42:47.828844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.686425ms","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-07-14T17:42:47.828917Z","caller":"traceutil/trace.go:171","msg":"trace[1655780632] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5712; }","duration":"167.773006ms","start":"2026-07-14T17:42:47.66112Z","end":"2026-07-14T17:42:47.828893Z","steps":["trace[1655780632] 'agreement among raft nodes before linearized reading' (duration: 167.521772ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:42:47.828922Z","caller":"traceutil/trace.go:171","msg":"trace[1819526319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5712; }","duration":"167.787416ms","start":"2026-07-14T17:42:47.661114Z","end":"2026-07-14T17:42:47.828901Z","steps":["trace[1819526319] 'agreement among raft nodes before linearized reading' (duration: 167.561802ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:43:14.129825Z","caller":"traceutil/trace.go:171","msg":"trace[1920659108] linearizableReadLoop","detail":"{readStateIndex:6055; appliedIndex:6053; }","duration":"175.034228ms","start":"2026-07-14T17:43:13.954768Z","end":"2026-07-14T17:43:14.129802Z","steps":["trace[1920659108] 'read index received' (duration: 25.505515ms)","trace[1920659108] 'applied index is now lower than readState.Index' (duration: 149.527683ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:43:14.130035Z","caller":"traceutil/trace.go:171","msg":"trace[1595031112] transaction","detail":"{read_only:false; response_revision:5844; number_of_response:1; }","duration":"182.126761ms","start":"2026-07-14T17:43:13.947875Z","end":"2026-07-14T17:43:14.130002Z","steps":["trace[1595031112] 'process raft request' (duration: 181.775216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:43:14.130213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.413664ms","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-07-14T17:43:14.1303Z","caller":"traceutil/trace.go:171","msg":"trace[1130909450] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5844; }","duration":"175.527116ms","start":"2026-07-14T17:43:13.954752Z","end":"2026-07-14T17:43:14.130279Z","steps":["trace[1130909450] 'agreement among raft nodes before linearized reading' (duration: 175.218231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:43:14.1303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.397614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-14T17:43:14.13035Z","caller":"traceutil/trace.go:171","msg":"trace[1999867755] range","detail":"{range_begin:/registry/masterleases/199.19.213.119; range_end:; response_count:1; response_revision:5844; }","duration":"158.499166ms","start":"2026-07-14T17:43:13.971837Z","end":"2026-07-14T17:43:14.130336Z","steps":["trace[1999867755] 'agreement among raft nodes before linearized reading' (duration: 158.234651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:43:14.130424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.728951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-14T17:43:14.130484Z","caller":"traceutil/trace.go:171","msg":"trace[1150291398] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5844; }","duration":"172.829583ms","start":"2026-07-14T17:43:13.957636Z","end":"2026-07-14T17:43:14.130465Z","steps":["trace[1150291398] 'agreement among raft nodes before linearized reading' (duration: 172.418636ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:41.410818Z","caller":"traceutil/trace.go:171","msg":"trace[1092004843] linearizableReadLoop","detail":"{readStateIndex:6722; appliedIndex:6721; }","duration":"348.286574ms","start":"2026-07-14T17:44:41.062495Z","end":"2026-07-14T17:44:41.410781Z","steps":["trace[1092004843] 'read index received' (duration: 326.481836ms)","trace[1092004843] 'applied index is now lower than readState.Index' (duration: 21.803318ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:44:41.410827Z","caller":"traceutil/trace.go:171","msg":"trace[1720337893] transaction","detail":"{read_only:false; response_revision:6488; number_of_response:1; }","duration":"353.343066ms","start":"2026-07-14T17:44:41.057449Z","end":"2026-07-14T17:44:41.410792Z","steps":["trace[1720337893] 'process raft request' (duration: 331.541258ms)","trace[1720337893] 'compare' (duration: 21.573468ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:44:41.41103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:41.057423Z","time spent":"353.521543ms","remote":"127.0.0.1:50972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6469,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:44:41.411066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.551674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-07-14T17:44:41.411108Z","caller":"traceutil/trace.go:171","msg":"trace[355572768] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:6488; }","duration":"348.643589ms","start":"2026-07-14T17:44:41.062449Z","end":"2026-07-14T17:44:41.411093Z","steps":["trace[355572768] 'agreement among raft nodes before linearized reading' (duration: 348.466451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:41.411144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:44:41.062432Z","time spent":"348.70313ms","remote":"127.0.0.1:33790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-14T17:44:41.477117Z","caller":"traceutil/trace.go:171","msg":"trace[1641112684] transaction","detail":"{read_only:false; response_revision:6490; number_of_response:1; }","duration":"273.378189ms","start":"2026-07-14T17:44:41.203716Z","end":"2026-07-14T17:44:41.477095Z","steps":["trace[1641112684] 'process raft request' (duration: 273.347748ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:44:41.477205Z","caller":"traceutil/trace.go:171","msg":"trace[1838977462] transaction","detail":"{read_only:false; response_revision:6489; number_of_response:1; }","duration":"275.28361ms","start":"2026-07-14T17:44:41.201894Z","end":"2026-07-14T17:44:41.477178Z","steps":["trace[1838977462] 'process raft request' (duration: 275.060521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:42.900407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.023884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:44:42.900931Z","caller":"traceutil/trace.go:171","msg":"trace[1272419950] linearizableReadLoop","detail":"{readStateIndex:6732; appliedIndex:6731; }","duration":"174.996411ms","start":"2026-07-14T17:44:42.725915Z","end":"2026-07-14T17:44:42.900911Z","steps":["trace[1272419950] 'read index received' (duration: 58.401643ms)","trace[1272419950] 'applied index is now lower than readState.Index' (duration: 116.592768ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:44:42.901169Z","caller":"traceutil/trace.go:171","msg":"trace[999120968] transaction","detail":"{read_only:false; response_revision:6498; number_of_response:1; }","duration":"182.533908ms","start":"2026-07-14T17:44:42.718586Z","end":"2026-07-14T17:44:42.90112Z","steps":["trace[999120968] 'process raft request' (duration: 65.721611ms)","trace[999120968] 'compare' (duration: 115.747192ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:44:42.901233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.311114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-14T17:44:42.901532Z","caller":"traceutil/trace.go:171","msg":"trace[57722066] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6498; }","duration":"175.623578ms","start":"2026-07-14T17:44:42.725867Z","end":"2026-07-14T17:44:42.901491Z","steps":["trace[57722066] 'agreement among raft nodes before linearized reading' (duration: 175.219101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:44:43.223663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.100164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:44:43.223746Z","caller":"traceutil/trace.go:171","msg":"trace[1548880767] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:6499; }","duration":"120.25942ms","start":"2026-07-14T17:44:43.103469Z","end":"2026-07-14T17:44:43.223728Z","steps":["trace[1548880767] 'count revisions from in-memory index tree' (duration: 119.957398ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:45:46.783317Z","caller":"traceutil/trace.go:171","msg":"trace[1284202414] transaction","detail":"{read_only:false; response_revision:6882; number_of_response:1; }","duration":"113.057911ms","start":"2026-07-14T17:45:46.67024Z","end":"2026-07-14T17:45:46.783298Z","steps":["trace[1284202414] 'process raft request' (duration: 112.730079ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:10.998383Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5442} {"level":"info","ts":"2026-07-14T17:47:11.08118Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5442,"took":"80.454571ms","hash":742685990,"current-db-size-bytes":52834304,"current-db-size":"53 MB","current-db-size-in-use-bytes":18395136,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-14T17:47:11.08125Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":742685990,"revision":5442,"compact-revision":2820}