{"level":"warn","ts":"2026-04-17T11:00:56.960149Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T11:00:56.960246Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-17T11:00:56.960313Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T11:00:56.960474Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-17T11:00:56.960602Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T11:00:56.96141Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-04-17T11:00:56.961607Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-17T11:00:56.966827Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.8933ms"} {"level":"info","ts":"2026-04-17T11:00:56.974243Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-04-17T11:00:56.97432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T11:00:56.974423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-04-17T11:00:56.974448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T11:00:56.97446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-04-17T11:00:56.974569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-04-17T11:00:56.980801Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T11:00:56.982368Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T11:00:56.983738Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-17T11:00:56.986023Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T11:00:56.986129Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T11:00:56.986317Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T11:00:56.986375Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T11:00:56.986384Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-17T11:00:56.988134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-04-17T11:00:56.988427Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-04-17T11:00:56.992696Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-17T11:00:56.992809Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-17T11:00:56.992948Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-04-17T11:00:56.993006Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T11:00:56.993051Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T11:00:57.47483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T11:00:57.474883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T11:00:57.474909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-04-17T11:00:57.474926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-04-17T11:00:57.474937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-17T11:00:57.474948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-04-17T11:00:57.474959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-04-17T11:00:57.516348Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T11:00:57.516401Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T11:00:57.516367Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T11:00:57.516326Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T11:00:57.517136Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T11:00:57.517199Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T11:00:57.518562Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T11:00:57.51885Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T11:00:57.519028Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T11:00:57.520514Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-04-17T11:00:57.522354Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T11:00:59.109766Z","caller":"traceutil/trace.go:171","msg":"trace[805222020] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"333.493831ms","start":"2026-04-17T11:00:58.77625Z","end":"2026-04-17T11:00:59.109743Z","steps":["trace[805222020] 'process raft request' (duration: 333.302608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:00:59.110478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:00:58.776241Z","time spent":"333.648504ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:00:59.118262Z","caller":"traceutil/trace.go:171","msg":"trace[1064999396] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"341.89826ms","start":"2026-04-17T11:00:58.776345Z","end":"2026-04-17T11:00:59.118243Z","steps":["trace[1064999396] 'process raft request' (duration: 341.497635ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:00:59.118282Z","caller":"traceutil/trace.go:171","msg":"trace[1879665957] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"265.664336ms","start":"2026-04-17T11:00:58.852536Z","end":"2026-04-17T11:00:59.1182Z","steps":["trace[1879665957] 'process raft request' (duration: 265.453893ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:00:59.118544Z","caller":"traceutil/trace.go:171","msg":"trace[175577404] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"265.19919ms","start":"2026-04-17T11:00:58.853324Z","end":"2026-04-17T11:00:59.118523Z","steps":["trace[175577404] 'process raft request' (duration: 264.794666ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:00:59.118615Z","caller":"traceutil/trace.go:171","msg":"trace[1868074372] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"251.262995ms","start":"2026-04-17T11:00:58.86733Z","end":"2026-04-17T11:00:59.118593Z","steps":["trace[1868074372] 'process raft request' (duration: 250.81375ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:00:59.118767Z","caller":"traceutil/trace.go:171","msg":"trace[1705628] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"244.336012ms","start":"2026-04-17T11:00:58.874371Z","end":"2026-04-17T11:00:59.118707Z","steps":["trace[1705628] 'process raft request' (duration: 243.887507ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:00:59.118892Z","caller":"traceutil/trace.go:171","msg":"trace[1490464194] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"266.300282ms","start":"2026-04-17T11:00:58.852574Z","end":"2026-04-17T11:00:59.118874Z","steps":["trace[1490464194] 'process raft request' (duration: 265.466753ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:00:59.118799Z","caller":"traceutil/trace.go:171","msg":"trace[1678006056] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"266.298303ms","start":"2026-04-17T11:00:58.852354Z","end":"2026-04-17T11:00:59.118652Z","steps":["trace[1678006056] 'process raft request' (duration: 265.603654ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:00:59.118806Z","caller":"traceutil/trace.go:171","msg":"trace[1905181614] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"266.162731ms","start":"2026-04-17T11:00:58.852562Z","end":"2026-04-17T11:00:59.118724Z","steps":["trace[1905181614] 'process raft request' (duration: 265.504153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:00:59.118558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.98769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T11:00:59.119029Z","caller":"traceutil/trace.go:171","msg":"trace[882232412] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"325.503266ms","start":"2026-04-17T11:00:58.793503Z","end":"2026-04-17T11:00:59.119006Z","steps":["trace[882232412] 'agreement among raft nodes before linearized reading' (duration: 324.949859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:00:59.119133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:00:58.79346Z","time spent":"325.656247ms","remote":"127.0.0.1:44574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-17T11:00:59.118402Z","caller":"traceutil/trace.go:171","msg":"trace[1760566071] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"324.767777ms","start":"2026-04-17T11:00:58.793532Z","end":"2026-04-17T11:00:59.118299Z","steps":["trace[1760566071] 'read index received' (duration: 316.071105ms)","trace[1760566071] 'applied index is now lower than readState.Index' (duration: 8.695582ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:00:59.11884Z","caller":"traceutil/trace.go:171","msg":"trace[225189976] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"266.051979ms","start":"2026-04-17T11:00:58.852755Z","end":"2026-04-17T11:00:59.118807Z","steps":["trace[225189976] 'process raft request' (duration: 265.339091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:00:59.11935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.356384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T11:00:59.119382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.478776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T11:00:59.118374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:00:58.776331Z","time spent":"341.986872ms","remote":"127.0.0.1:44230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:00:59.119445Z","caller":"traceutil/trace.go:171","msg":"trace[1412081151] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"267.542516ms","start":"2026-04-17T11:00:58.851878Z","end":"2026-04-17T11:00:59.119421Z","steps":["trace[1412081151] 'agreement among raft nodes before linearized reading' (duration: 267.444055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:00:59.119492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.162856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T11:00:59.119553Z","caller":"traceutil/trace.go:171","msg":"trace[2002663170] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"290.259708ms","start":"2026-04-17T11:00:58.829279Z","end":"2026-04-17T11:00:59.119539Z","steps":["trace[2002663170] 'agreement among raft nodes before linearized reading' (duration: 290.166147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:00:59.119626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.270009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T11:00:59.119643Z","caller":"traceutil/trace.go:171","msg":"trace[2106905030] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"293.430235ms","start":"2026-04-17T11:00:58.825942Z","end":"2026-04-17T11:00:59.119372Z","steps":["trace[2106905030] 'agreement among raft nodes before linearized reading' (duration: 293.371574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:00:59.119597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.352188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T11:00:59.119663Z","caller":"traceutil/trace.go:171","msg":"trace[1501764410] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"216.33182ms","start":"2026-04-17T11:00:58.903322Z","end":"2026-04-17T11:00:59.119654Z","steps":["trace[1501764410] 'agreement among raft nodes before linearized reading' (duration: 216.28194ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:00:59.119681Z","caller":"traceutil/trace.go:171","msg":"trace[766968705] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"290.46012ms","start":"2026-04-17T11:00:58.829206Z","end":"2026-04-17T11:00:59.119666Z","steps":["trace[766968705] 'agreement among raft nodes before linearized reading' (duration: 290.297468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:00:59.119633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.414738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-17T11:00:59.119723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.108222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-17T11:00:59.119753Z","caller":"traceutil/trace.go:171","msg":"trace[2089025250] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"267.135472ms","start":"2026-04-17T11:00:58.852606Z","end":"2026-04-17T11:00:59.119742Z","steps":["trace[2089025250] 'agreement among raft nodes before linearized reading' (duration: 267.099212ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:00:59.119882Z","caller":"traceutil/trace.go:171","msg":"trace[1419442887] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:0; response_revision:11; }","duration":"268.60296ms","start":"2026-04-17T11:00:58.851178Z","end":"2026-04-17T11:00:59.119781Z","steps":["trace[1419442887] 'agreement among raft nodes before linearized reading' (duration: 268.363287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:02.034449Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495438592184302,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T11:01:02.827933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.352347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T11:01:02.828032Z","caller":"traceutil/trace.go:171","msg":"trace[1197815806] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:210; }","duration":"1.294047828s","start":"2026-04-17T11:01:01.533952Z","end":"2026-04-17T11:01:02.828Z","steps":["trace[1197815806] 'read index received' (duration: 816.333467ms)","trace[1197815806] 'applied index is now lower than readState.Index' (duration: 477.713381ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:01:02.828036Z","caller":"traceutil/trace.go:171","msg":"trace[474180167] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"1.466851136s","start":"2026-04-17T11:01:01.361162Z","end":"2026-04-17T11:01:02.828013Z","steps":["trace[474180167] 'process raft request' (duration: 989.269267ms)","trace[474180167] 'compare' (duration: 477.224095ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:01:02.828122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:01.361152Z","time spent":"1.466937317s","remote":"127.0.0.1:44268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11436,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T11:01:02.82814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294186909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:01:02.828164Z","caller":"traceutil/trace.go:171","msg":"trace[1587766130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:206; }","duration":"1.2942659s","start":"2026-04-17T11:01:01.533891Z","end":"2026-04-17T11:01:02.828157Z","steps":["trace[1587766130] 'agreement among raft nodes before linearized reading' (duration: 1.294195879s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:02.8282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:01.53386Z","time spent":"1.294333251s","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T11:01:02.828398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04064464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:01:02.828425Z","caller":"traceutil/trace.go:171","msg":"trace[1411492195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:206; }","duration":"1.040720622s","start":"2026-04-17T11:01:01.787698Z","end":"2026-04-17T11:01:02.828419Z","steps":["trace[1411492195] 'agreement among raft nodes before linearized reading' (duration: 1.040675531s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:02.828427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.524077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T11:01:02.828443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:01.787673Z","time spent":"1.040766782s","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T11:01:02.828467Z","caller":"traceutil/trace.go:171","msg":"trace[1857693782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:206; }","duration":"244.563647ms","start":"2026-04-17T11:01:02.583893Z","end":"2026-04-17T11:01:02.828457Z","steps":["trace[1857693782] 'agreement among raft nodes before linearized reading' (duration: 244.509226ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:03.015415Z","caller":"traceutil/trace.go:171","msg":"trace[1934235004] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"183.860535ms","start":"2026-04-17T11:01:02.831545Z","end":"2026-04-17T11:01:03.015405Z","steps":["trace[1934235004] 'process raft request' (duration: 183.753534ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:03.015403Z","caller":"traceutil/trace.go:171","msg":"trace[359028856] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"183.827234ms","start":"2026-04-17T11:01:02.831542Z","end":"2026-04-17T11:01:03.015369Z","steps":["trace[359028856] 'process raft request' (duration: 177.239961ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:03.015444Z","caller":"traceutil/trace.go:171","msg":"trace[740205686] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"182.623061ms","start":"2026-04-17T11:01:02.832798Z","end":"2026-04-17T11:01:03.015421Z","steps":["trace[740205686] 'process raft request' (duration: 182.584451ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:03.015661Z","caller":"traceutil/trace.go:171","msg":"trace[884542700] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"183.756624ms","start":"2026-04-17T11:01:02.831841Z","end":"2026-04-17T11:01:03.015598Z","steps":["trace[884542700] 'process raft request' (duration: 183.507631ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:04.125042Z","caller":"traceutil/trace.go:171","msg":"trace[981541546] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:252; }","duration":"255.647848ms","start":"2026-04-17T11:01:03.869369Z","end":"2026-04-17T11:01:04.125017Z","steps":["trace[981541546] 'read index received' (duration: 252.571574ms)","trace[981541546] 'applied index is now lower than readState.Index' (duration: 3.075344ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:01:04.125324Z","caller":"traceutil/trace.go:171","msg":"trace[225215823] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"276.842524ms","start":"2026-04-17T11:01:03.848455Z","end":"2026-04-17T11:01:04.125298Z","steps":["trace[225215823] 'process raft request' (duration: 273.432186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:04.125479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.077892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-17T11:01:04.125594Z","caller":"traceutil/trace.go:171","msg":"trace[2043236069] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:247; }","duration":"256.235444ms","start":"2026-04-17T11:01:03.869338Z","end":"2026-04-17T11:01:04.125573Z","steps":["trace[2043236069] 'agreement among raft nodes before linearized reading' (duration: 255.973121ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:04.131119Z","caller":"traceutil/trace.go:171","msg":"trace[582892091] transaction","detail":"{read_only:false; number_of_response:0; response_revision:248; }","duration":"127.260963ms","start":"2026-04-17T11:01:04.003832Z","end":"2026-04-17T11:01:04.131093Z","steps":["trace[582892091] 'process raft request' (duration: 127.123682ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:04.131222Z","caller":"traceutil/trace.go:171","msg":"trace[1773632269] transaction","detail":"{read_only:false; number_of_response:0; response_revision:248; }","duration":"114.480983ms","start":"2026-04-17T11:01:04.016722Z","end":"2026-04-17T11:01:04.131203Z","steps":["trace[1773632269] 'process raft request' (duration: 114.266881ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:04.131416Z","caller":"traceutil/trace.go:171","msg":"trace[595986697] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"230.128744ms","start":"2026-04-17T11:01:03.90127Z","end":"2026-04-17T11:01:04.131399Z","steps":["trace[595986697] 'process raft request' (duration: 229.586339ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:04.131588Z","caller":"traceutil/trace.go:171","msg":"trace[505209695] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"114.610215ms","start":"2026-04-17T11:01:04.016966Z","end":"2026-04-17T11:01:04.131576Z","steps":["trace[505209695] 'process raft request' (duration: 114.07245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:04.131717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.439608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-17T11:01:04.131798Z","caller":"traceutil/trace.go:171","msg":"trace[998629509] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:252; }","duration":"230.542868ms","start":"2026-04-17T11:01:03.901208Z","end":"2026-04-17T11:01:04.131751Z","steps":["trace[998629509] 'agreement among raft nodes before linearized reading' (duration: 230.406737ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:04.132057Z","caller":"traceutil/trace.go:171","msg":"trace[702719346] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"114.387161ms","start":"2026-04-17T11:01:04.017651Z","end":"2026-04-17T11:01:04.132038Z","steps":["trace[702719346] 'process raft request' (duration: 113.485442ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:04.13214Z","caller":"traceutil/trace.go:171","msg":"trace[954185881] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"114.724986ms","start":"2026-04-17T11:01:04.017409Z","end":"2026-04-17T11:01:04.132134Z","steps":["trace[954185881] 'process raft request' (duration: 113.701325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:04.132376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.956433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:01:04.13242Z","caller":"traceutil/trace.go:171","msg":"trace[1289773401] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:252; }","duration":"231.041385ms","start":"2026-04-17T11:01:03.901367Z","end":"2026-04-17T11:01:04.132409Z","steps":["trace[1289773401] 'agreement among raft nodes before linearized reading' (duration: 230.967823ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:04.133481Z","caller":"traceutil/trace.go:171","msg":"trace[24516653] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"116.123092ms","start":"2026-04-17T11:01:04.017346Z","end":"2026-04-17T11:01:04.133469Z","steps":["trace[24516653] 'process raft request' (duration: 113.740976ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:24.074892Z","caller":"traceutil/trace.go:171","msg":"trace[868149813] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"118.764346ms","start":"2026-04-17T11:01:23.956104Z","end":"2026-04-17T11:01:24.074868Z","steps":["trace[868149813] 'process raft request' (duration: 118.614925ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:24.081027Z","caller":"traceutil/trace.go:171","msg":"trace[1974745857] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"107.237765ms","start":"2026-04-17T11:01:23.973779Z","end":"2026-04-17T11:01:24.081016Z","steps":["trace[1974745857] 'process raft request' (duration: 106.938122ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:24.081138Z","caller":"traceutil/trace.go:171","msg":"trace[752323363] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"118.111202ms","start":"2026-04-17T11:01:23.963019Z","end":"2026-04-17T11:01:24.08113Z","steps":["trace[752323363] 'process raft request' (duration: 117.517796ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:24.081213Z","caller":"traceutil/trace.go:171","msg":"trace[693945448] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"116.372926ms","start":"2026-04-17T11:01:23.964833Z","end":"2026-04-17T11:01:24.081206Z","steps":["trace[693945448] 'process raft request' (duration: 115.843381ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:24.081296Z","caller":"traceutil/trace.go:171","msg":"trace[1408510893] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"103.51811ms","start":"2026-04-17T11:01:23.977771Z","end":"2026-04-17T11:01:24.081289Z","steps":["trace[1408510893] 'process raft request' (duration: 103.194587ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:24.081374Z","caller":"traceutil/trace.go:171","msg":"trace[1257470915] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"106.312506ms","start":"2026-04-17T11:01:23.975056Z","end":"2026-04-17T11:01:24.081369Z","steps":["trace[1257470915] 'process raft request' (duration: 105.712301ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:24.081424Z","caller":"traceutil/trace.go:171","msg":"trace[745442426] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"104.606741ms","start":"2026-04-17T11:01:23.976812Z","end":"2026-04-17T11:01:24.081419Z","steps":["trace[745442426] 'process raft request' (duration: 103.997776ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:24.081469Z","caller":"traceutil/trace.go:171","msg":"trace[2027869532] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"103.947524ms","start":"2026-04-17T11:01:23.977515Z","end":"2026-04-17T11:01:24.081463Z","steps":["trace[2027869532] 'process raft request' (duration: 103.3825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:24.62203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.654467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:7343"} {"level":"info","ts":"2026-04-17T11:01:24.6222Z","caller":"traceutil/trace.go:171","msg":"trace[1604839143] transaction","detail":"{read_only:false; number_of_response:1; response_revision:472; }","duration":"535.278449ms","start":"2026-04-17T11:01:24.086897Z","end":"2026-04-17T11:01:24.622175Z","steps":["trace[1604839143] 'process raft request' (duration: 242.35277ms)","trace[1604839143] 'compare' (duration: 292.556396ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:01:24.622292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:24.086879Z","time spent":"535.36511ms","remote":"127.0.0.1:44218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:01:24.623146Z","caller":"traceutil/trace.go:171","msg":"trace[881832577] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"507.798214ms","start":"2026-04-17T11:01:24.115331Z","end":"2026-04-17T11:01:24.623129Z","steps":["trace[881832577] 'process raft request' (duration: 507.648973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:24.623239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:24.115315Z","time spent":"507.878216ms","remote":"127.0.0.1:44218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:01:24.623531Z","caller":"traceutil/trace.go:171","msg":"trace[967492412] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"507.713923ms","start":"2026-04-17T11:01:24.115801Z","end":"2026-04-17T11:01:24.623515Z","steps":["trace[967492412] 'process raft request' (duration: 507.524872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:24.623616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:24.115797Z","time spent":"507.783894ms","remote":"127.0.0.1:44728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T11:01:24.623649Z","caller":"traceutil/trace.go:171","msg":"trace[2009785438] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"445.908025ms","start":"2026-04-17T11:01:24.177723Z","end":"2026-04-17T11:01:24.623631Z","steps":["trace[2009785438] 'process raft request' (duration: 445.862505ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:24.623662Z","caller":"traceutil/trace.go:171","msg":"trace[974313549] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"507.865515ms","start":"2026-04-17T11:01:24.11577Z","end":"2026-04-17T11:01:24.623635Z","steps":["trace[974313549] 'process raft request' (duration: 507.501272ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:24.623675Z","caller":"traceutil/trace.go:171","msg":"trace[1674578550] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"477.97684ms","start":"2026-04-17T11:01:24.145668Z","end":"2026-04-17T11:01:24.623644Z","steps":["trace[1674578550] 'process raft request' (duration: 477.718097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:24.623757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:24.17771Z","time spent":"445.997486ms","remote":"127.0.0.1:44218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T11:01:24.623759Z","caller":"traceutil/trace.go:171","msg":"trace[1493824092] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"468.346534ms","start":"2026-04-17T11:01:24.15539Z","end":"2026-04-17T11:01:24.623737Z","steps":["trace[1493824092] 'process raft request' (duration: 468.070052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:24.623764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:24.115765Z","time spent":"507.953426ms","remote":"127.0.0.1:44728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T11:01:24.623832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:24.15537Z","time spent":"468.421315ms","remote":"127.0.0.1:44218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:01:24.623832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:24.145659Z","time spent":"478.09426ms","remote":"127.0.0.1:44218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:01:24.623894Z","caller":"traceutil/trace.go:171","msg":"trace[1708053326] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"468.405305ms","start":"2026-04-17T11:01:24.15548Z","end":"2026-04-17T11:01:24.623885Z","steps":["trace[1708053326] 'process raft request' (duration: 468.022151ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:24.62391Z","caller":"traceutil/trace.go:171","msg":"trace[799106036] transaction","detail":"{read_only:false; number_of_response:1; response_revision:478; }","duration":"468.842569ms","start":"2026-04-17T11:01:24.15505Z","end":"2026-04-17T11:01:24.623892Z","steps":["trace[799106036] 'process raft request' (duration: 468.380965ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:24.623952Z","caller":"traceutil/trace.go:171","msg":"trace[1441080966] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"500.915323ms","start":"2026-04-17T11:01:24.123026Z","end":"2026-04-17T11:01:24.623942Z","steps":["trace[1441080966] 'process raft request' (duration: 500.319598ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:24.623989Z","caller":"traceutil/trace.go:171","msg":"trace[1262478016] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"478.315052ms","start":"2026-04-17T11:01:24.145668Z","end":"2026-04-17T11:01:24.623983Z","steps":["trace[1262478016] 'process raft request' (duration: 477.695907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:24.624005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:24.12301Z","time spent":"500.973373ms","remote":"127.0.0.1:44728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T11:01:24.624021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:24.145653Z","time spent":"478.355143ms","remote":"127.0.0.1:44728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T11:01:24.624015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:24.155031Z","time spent":"468.932329ms","remote":"127.0.0.1:44218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:01:24.623938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:24.155474Z","time spent":"468.442635ms","remote":"127.0.0.1:44218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:01:24.623962Z","caller":"traceutil/trace.go:171","msg":"trace[904620946] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"458.174394ms","start":"2026-04-17T11:01:24.165781Z","end":"2026-04-17T11:01:24.623956Z","steps":["trace[904620946] 'process raft request' (duration: 457.758621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:24.624213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:24.165773Z","time spent":"458.413727ms","remote":"127.0.0.1:44218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:01:24.624955Z","caller":"traceutil/trace.go:171","msg":"trace[786545521] transaction","detail":"{read_only:false; number_of_response:0; response_revision:473; }","duration":"507.873215ms","start":"2026-04-17T11:01:24.115715Z","end":"2026-04-17T11:01:24.623589Z","steps":["trace[786545521] 'process raft request' (duration: 507.383721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:24.625091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:24.115707Z","time spent":"509.316648ms","remote":"127.0.0.1:44728","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T11:01:29.099042Z","caller":"traceutil/trace.go:171","msg":"trace[1752864643] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"111.890005ms","start":"2026-04-17T11:01:28.987126Z","end":"2026-04-17T11:01:29.099016Z","steps":["trace[1752864643] 'process raft request' (duration: 110.877327ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:29.871952Z","caller":"traceutil/trace.go:171","msg":"trace[1162395608] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"176.460454ms","start":"2026-04-17T11:01:29.695465Z","end":"2026-04-17T11:01:29.871926Z","steps":["trace[1162395608] 'process raft request' (duration: 176.330743ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:29.881599Z","caller":"traceutil/trace.go:171","msg":"trace[1593635199] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"123.596393ms","start":"2026-04-17T11:01:29.757985Z","end":"2026-04-17T11:01:29.881582Z","steps":["trace[1593635199] 'process raft request' (duration: 123.491573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:30.528087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.327768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:01:30.528209Z","caller":"traceutil/trace.go:171","msg":"trace[1040119886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"329.48328ms","start":"2026-04-17T11:01:30.198705Z","end":"2026-04-17T11:01:30.528188Z","steps":["trace[1040119886] 'range keys from in-memory index tree' (duration: 329.246528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:30.528246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:30.198692Z","time spent":"329.54566ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T11:01:30.891932Z","caller":"traceutil/trace.go:171","msg":"trace[305704480] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"377.642546ms","start":"2026-04-17T11:01:30.514264Z","end":"2026-04-17T11:01:30.891906Z","steps":["trace[305704480] 'process raft request' (duration: 377.042902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:30.892131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:30.51423Z","time spent":"377.791478ms","remote":"127.0.0.1:44268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:01:30.922327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:30.529875Z","time spent":"392.448422ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-17T11:01:32.034056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102460418s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T11:01:32.034221Z","caller":"traceutil/trace.go:171","msg":"trace[280351428] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:589; }","duration":"852.855597ms","start":"2026-04-17T11:01:31.181349Z","end":"2026-04-17T11:01:32.034204Z","steps":["trace[280351428] 'read index received' (duration: 34.811µs)","trace[280351428] 'applied index is now lower than readState.Index' (duration: 852.819636ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:01:32.034305Z","caller":"traceutil/trace.go:171","msg":"trace[150099892] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.110986561s","start":"2026-04-17T11:01:30.92331Z","end":"2026-04-17T11:01:32.034296Z","steps":["trace[150099892] 'compare' (duration: 1.102303857s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:32.034388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:30.923283Z","time spent":"1.111047181s","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:01:32.034726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.733694ms","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-04-17T11:01:32.034704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.30807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-17T11:01:32.034758Z","caller":"traceutil/trace.go:171","msg":"trace[500924451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"846.784634ms","start":"2026-04-17T11:01:31.187965Z","end":"2026-04-17T11:01:32.034749Z","steps":["trace[500924451] 'agreement among raft nodes before linearized reading' (duration: 846.664423ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:32.034811Z","caller":"traceutil/trace.go:171","msg":"trace[1791302920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"853.472401ms","start":"2026-04-17T11:01:31.181292Z","end":"2026-04-17T11:01:32.034764Z","steps":["trace[1791302920] 'agreement among raft nodes before linearized reading' (duration: 853.130209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:32.034821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:31.187955Z","time spent":"846.859625ms","remote":"127.0.0.1:44424","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-04-17T11:01:32.034858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:31.181269Z","time spent":"853.579092ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T11:01:32.034838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.457793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T11:01:32.034906Z","caller":"traceutil/trace.go:171","msg":"trace[1624863190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"589.621854ms","start":"2026-04-17T11:01:31.445266Z","end":"2026-04-17T11:01:32.034888Z","steps":["trace[1624863190] 'agreement among raft nodes before linearized reading' (duration: 589.381363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:32.034948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:31.44524Z","time spent":"589.699275ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T11:01:32.034993Z","caller":"traceutil/trace.go:171","msg":"trace[903017384] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.073463262s","start":"2026-04-17T11:01:30.961514Z","end":"2026-04-17T11:01:32.034978Z","steps":["trace[903017384] 'process raft request' (duration: 1.072645016s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:32.035063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:30.961488Z","time spent":"1.073527672s","remote":"127.0.0.1:44708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T11:01:32.035138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.371671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T11:01:32.035218Z","caller":"traceutil/trace.go:171","msg":"trace[807611391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"846.466072ms","start":"2026-04-17T11:01:31.188732Z","end":"2026-04-17T11:01:32.035198Z","steps":["trace[807611391] 'agreement among raft nodes before linearized reading' (duration: 846.20541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:32.035264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:31.188667Z","time spent":"846.587433ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T11:01:32.035291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.134654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:01:32.035326Z","caller":"traceutil/trace.go:171","msg":"trace[931859498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"837.187705ms","start":"2026-04-17T11:01:31.198128Z","end":"2026-04-17T11:01:32.035316Z","steps":["trace[931859498] 'agreement among raft nodes before linearized reading' (duration: 837.133794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:32.035356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:31.198118Z","time spent":"837.229455ms","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T11:01:32.360448Z","caller":"traceutil/trace.go:171","msg":"trace[741693641] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"318.341667ms","start":"2026-04-17T11:01:32.042079Z","end":"2026-04-17T11:01:32.360421Z","steps":["trace[741693641] 'process raft request' (duration: 318.042175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:32.36066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:32.042067Z","time spent":"318.466278ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:01:32.362392Z","caller":"traceutil/trace.go:171","msg":"trace[25535162] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"320.04168ms","start":"2026-04-17T11:01:32.042328Z","end":"2026-04-17T11:01:32.36237Z","steps":["trace[25535162] 'process raft request' (duration: 319.900509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:32.362533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:32.04232Z","time spent":"320.132471ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:01:32.362809Z","caller":"traceutil/trace.go:171","msg":"trace[143884806] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"320.384914ms","start":"2026-04-17T11:01:32.042379Z","end":"2026-04-17T11:01:32.362763Z","steps":["trace[143884806] 'process raft request' (duration: 319.95512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:32.363045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:32.04236Z","time spent":"320.522815ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:01:32.363141Z","caller":"traceutil/trace.go:171","msg":"trace[332891606] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:591; }","duration":"320.251482ms","start":"2026-04-17T11:01:32.042863Z","end":"2026-04-17T11:01:32.363115Z","steps":["trace[332891606] 'read index received' (duration: 317.405239ms)","trace[332891606] 'applied index is now lower than readState.Index' (duration: 2.843863ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:01:32.363196Z","caller":"traceutil/trace.go:171","msg":"trace[731653533] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"310.934385ms","start":"2026-04-17T11:01:32.052245Z","end":"2026-04-17T11:01:32.363179Z","steps":["trace[731653533] 'process raft request' (duration: 310.445511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:32.363281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:32.052227Z","time spent":"311.013845ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:01:32.363402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.895946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:01:32.363485Z","caller":"traceutil/trace.go:171","msg":"trace[1441081716] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:573; }","duration":"315.994097ms","start":"2026-04-17T11:01:32.047442Z","end":"2026-04-17T11:01:32.363436Z","steps":["trace[1441081716] 'agreement among raft nodes before linearized reading' (duration: 315.840886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:32.363524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.576198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T11:01:32.36353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:32.047434Z","time spent":"316.085168ms","remote":"127.0.0.1:44404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-17T11:01:32.363628Z","caller":"traceutil/trace.go:171","msg":"trace[1329157263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"155.653678ms","start":"2026-04-17T11:01:32.207933Z","end":"2026-04-17T11:01:32.363587Z","steps":["trace[1329157263] 'agreement among raft nodes before linearized reading' (duration: 155.452087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:32.363778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.898858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-17T11:01:32.363878Z","caller":"traceutil/trace.go:171","msg":"trace[971323016] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:573; }","duration":"321.014438ms","start":"2026-04-17T11:01:32.042846Z","end":"2026-04-17T11:01:32.363861Z","steps":["trace[971323016] 'agreement among raft nodes before linearized reading' (duration: 320.410964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:32.36395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:32.04284Z","time spent":"321.098099ms","remote":"127.0.0.1:44438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-04-17T11:01:37.309646Z","caller":"traceutil/trace.go:171","msg":"trace[1222098424] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"109.357704ms","start":"2026-04-17T11:01:37.200266Z","end":"2026-04-17T11:01:37.309624Z","steps":["trace[1222098424] 'read index received' (duration: 109.008401ms)","trace[1222098424] 'applied index is now lower than readState.Index' (duration: 348.713µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:01:37.309775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.499355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:01:37.309801Z","caller":"traceutil/trace.go:171","msg":"trace[1843293689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"109.570405ms","start":"2026-04-17T11:01:37.200222Z","end":"2026-04-17T11:01:37.309792Z","steps":["trace[1843293689] 'agreement among raft nodes before linearized reading' (duration: 109.490345ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:37.30995Z","caller":"traceutil/trace.go:171","msg":"trace[236927855] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"136.374892ms","start":"2026-04-17T11:01:37.173531Z","end":"2026-04-17T11:01:37.309906Z","steps":["trace[236927855] 'process raft request' (duration: 135.944459ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:45.50342Z","caller":"traceutil/trace.go:171","msg":"trace[208494043] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"304.480604ms","start":"2026-04-17T11:01:45.198917Z","end":"2026-04-17T11:01:45.503397Z","steps":["trace[208494043] 'read index received' (duration: 304.247582ms)","trace[208494043] 'applied index is now lower than readState.Index' (duration: 232.072µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:01:45.503534Z","caller":"traceutil/trace.go:171","msg":"trace[1892892412] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"310.902114ms","start":"2026-04-17T11:01:45.192605Z","end":"2026-04-17T11:01:45.503507Z","steps":["trace[1892892412] 'process raft request' (duration: 310.652842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:45.503603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.672695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:01:45.503666Z","caller":"traceutil/trace.go:171","msg":"trace[259852950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"304.782336ms","start":"2026-04-17T11:01:45.198873Z","end":"2026-04-17T11:01:45.503655Z","steps":["trace[259852950] 'agreement among raft nodes before linearized reading' (duration: 304.627195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:01:45.503715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:45.198846Z","time spent":"304.848287ms","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T11:01:45.503741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:01:45.192582Z","time spent":"311.056265ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:01:48.045977Z","caller":"traceutil/trace.go:171","msg":"trace[1997942501] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"230.142711ms","start":"2026-04-17T11:01:47.815802Z","end":"2026-04-17T11:01:48.045945Z","steps":["trace[1997942501] 'process raft request' (duration: 229.939349ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:01:48.289946Z","caller":"traceutil/trace.go:171","msg":"trace[1752163403] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"174.638361ms","start":"2026-04-17T11:01:48.115279Z","end":"2026-04-17T11:01:48.289918Z","steps":["trace[1752163403] 'process raft request' (duration: 174.311998ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:02:12.411119Z","caller":"traceutil/trace.go:171","msg":"trace[1214439002] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"119.478575ms","start":"2026-04-17T11:02:12.291617Z","end":"2026-04-17T11:02:12.411095Z","steps":["trace[1214439002] 'process raft request' (duration: 119.307341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:03:35.79019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.766511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T11:03:35.790322Z","caller":"traceutil/trace.go:171","msg":"trace[1084287715] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"256.900511ms","start":"2026-04-17T11:03:35.5334Z","end":"2026-04-17T11:03:35.7903Z","steps":["trace[1084287715] 'process raft request' (duration: 22.942518ms)","trace[1084287715] 'compare' (duration: 233.537018ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:03:46.52725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.215208ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T11:03:46.52737Z","caller":"traceutil/trace.go:171","msg":"trace[2017917769] linearizableReadLoop","detail":"{readStateIndex:1556; appliedIndex:1555; }","duration":"606.68374ms","start":"2026-04-17T11:03:45.920668Z","end":"2026-04-17T11:03:46.527352Z","steps":["trace[2017917769] 'read index received' (duration: 33.471µs)","trace[2017917769] 'applied index is now lower than readState.Index' (duration: 606.648839ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:03:46.527476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.796042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:03:46.527511Z","caller":"traceutil/trace.go:171","msg":"trace[165419034] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1503; }","duration":"606.864912ms","start":"2026-04-17T11:03:45.920636Z","end":"2026-04-17T11:03:46.527501Z","steps":["trace[165419034] 'agreement among raft nodes before linearized reading' (duration: 606.787591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:03:46.527545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:03:45.920618Z","time spent":"606.917923ms","remote":"127.0.0.1:44472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-17T11:03:46.651404Z","caller":"traceutil/trace.go:171","msg":"trace[347110718] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"614.243535ms","start":"2026-04-17T11:03:46.037137Z","end":"2026-04-17T11:03:46.651381Z","steps":["trace[347110718] 'process raft request' (duration: 614.076733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:03:46.651565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:03:46.037119Z","time spent":"614.362338ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:03:46.651893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.881881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T11:03:46.652053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.963494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/cert-manager/cmapichecker-46jp4\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:03:46.652216Z","caller":"traceutil/trace.go:171","msg":"trace[1449459523] linearizableReadLoop","detail":"{readStateIndex:1557; appliedIndex:1557; }","duration":"124.363929ms","start":"2026-04-17T11:03:46.527397Z","end":"2026-04-17T11:03:46.651761Z","steps":["trace[1449459523] 'read index received' (duration: 124.344399ms)","trace[1449459523] 'applied index is now lower than readState.Index' (duration: 15.34µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:03:46.652442Z","caller":"traceutil/trace.go:171","msg":"trace[1483776349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1504; }","duration":"452.018023ms","start":"2026-04-17T11:03:46.199946Z","end":"2026-04-17T11:03:46.651964Z","steps":["trace[1483776349] 'agreement among raft nodes before linearized reading' (duration: 451.865911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:03:46.6525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:03:46.199925Z","time spent":"452.562979ms","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T11:03:46.65262Z","caller":"traceutil/trace.go:171","msg":"trace[1752663146] range","detail":"{range_begin:/registry/cert-manager.io/certificates/cert-manager/cmapichecker-46jp4; range_end:; response_count:0; response_revision:1504; }","duration":"670.053106ms","start":"2026-04-17T11:03:45.98204Z","end":"2026-04-17T11:03:46.652093Z","steps":["trace[1752663146] 'agreement among raft nodes before linearized reading' (duration: 669.961495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:03:46.652685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:03:45.982003Z","time spent":"670.665992ms","remote":"127.0.0.1:48354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/cert-manager.io/certificates/cert-manager/cmapichecker-46jp4\" "} {"level":"warn","ts":"2026-04-17T11:03:46.653256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.447618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-4xcv8\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-04-17T11:03:46.653329Z","caller":"traceutil/trace.go:171","msg":"trace[965709251] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-4xcv8; range_end:; response_count:1; response_revision:1504; }","duration":"123.52486ms","start":"2026-04-17T11:03:46.529766Z","end":"2026-04-17T11:03:46.653291Z","steps":["trace[965709251] 'agreement among raft nodes before linearized reading' (duration: 122.728209ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:03:47.162206Z","caller":"traceutil/trace.go:171","msg":"trace[358550524] transaction","detail":"{read_only:false; response_revision:1506; number_of_response:1; }","duration":"170.817818ms","start":"2026-04-17T11:03:46.991362Z","end":"2026-04-17T11:03:47.16218Z","steps":["trace[358550524] 'process raft request' (duration: 170.674127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:09.815693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.119029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:7 size:242056"} {"level":"info","ts":"2026-04-17T11:04:09.815793Z","caller":"traceutil/trace.go:171","msg":"trace[1838943978] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:7; response_revision:2018; }","duration":"117.256841ms","start":"2026-04-17T11:04:09.698515Z","end":"2026-04-17T11:04:09.815772Z","steps":["trace[1838943978] 'range keys from in-memory index tree' (duration: 116.880397ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:14.79799Z","caller":"traceutil/trace.go:171","msg":"trace[1957160207] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"100.57677ms","start":"2026-04-17T11:04:14.697387Z","end":"2026-04-17T11:04:14.797964Z","steps":["trace[1957160207] 'process raft request' (duration: 100.446168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:15.821988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.574277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T11:04:15.822337Z","caller":"traceutil/trace.go:171","msg":"trace[887302902] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"1.02031497s","start":"2026-04-17T11:04:14.801982Z","end":"2026-04-17T11:04:15.822297Z","steps":["trace[887302902] 'process raft request' (duration: 795.294439ms)","trace[887302902] 'compare' (duration: 224.085041ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:04:15.822412Z","caller":"traceutil/trace.go:171","msg":"trace[1116912000] linearizableReadLoop","detail":"{readStateIndex:2268; appliedIndex:2267; }","duration":"697.336884ms","start":"2026-04-17T11:04:15.125048Z","end":"2026-04-17T11:04:15.822384Z","steps":["trace[1116912000] 'read index received' (duration: 472.229142ms)","trace[1116912000] 'applied index is now lower than readState.Index' (duration: 225.105282ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:04:15.822548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:14.801956Z","time spent":"1.020478082s","remote":"127.0.0.1:44424","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-04-17T11:04:15.822544Z","caller":"traceutil/trace.go:171","msg":"trace[1761081924] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"478.135306ms","start":"2026-04-17T11:04:15.344377Z","end":"2026-04-17T11:04:15.822512Z","steps":["trace[1761081924] 'process raft request' (duration: 477.799112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:15.822708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:15.34435Z","time spent":"478.269107ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:04:15.928662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.311328ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T11:04:15.928775Z","caller":"traceutil/trace.go:171","msg":"trace[941712446] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2269; }","duration":"106.282858ms","start":"2026-04-17T11:04:15.82247Z","end":"2026-04-17T11:04:15.928753Z","steps":["trace[941712446] 'read index received' (duration: 67.781µs)","trace[941712446] 'applied index is now lower than readState.Index' (duration: 106.213267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:04:15.928875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.924526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:04:15.928913Z","caller":"traceutil/trace.go:171","msg":"trace[379307283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2178; }","duration":"729.032167ms","start":"2026-04-17T11:04:15.199869Z","end":"2026-04-17T11:04:15.928901Z","steps":["trace[379307283] 'agreement among raft nodes before linearized reading' (duration: 728.951266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:15.928948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:15.199847Z","time spent":"729.093147ms","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T11:04:15.929018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.6227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T11:04:15.929077Z","caller":"traceutil/trace.go:171","msg":"trace[1665470770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2178; }","duration":"328.722712ms","start":"2026-04-17T11:04:15.600341Z","end":"2026-04-17T11:04:15.929064Z","steps":["trace[1665470770] 'agreement among raft nodes before linearized reading' (duration: 328.5219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:15.929121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:15.600323Z","time spent":"328.789802ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T11:04:15.929229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.796906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b8efb381-98a3-4b31-9b23-c9be46e5cee8\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-17T11:04:15.929343Z","caller":"traceutil/trace.go:171","msg":"trace[1033719632] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b8efb381-98a3-4b31-9b23-c9be46e5cee8; range_end:; response_count:1; response_revision:2178; }","duration":"233.936047ms","start":"2026-04-17T11:04:15.695376Z","end":"2026-04-17T11:04:15.929312Z","steps":["trace[1033719632] 'agreement among raft nodes before linearized reading' (duration: 233.719515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:15.929384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.793818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cb4d6a07-e52c-4e48-a831-d99d256df671\" ","response":"range_response_count:1 size:3737"} {"level":"info","ts":"2026-04-17T11:04:15.929487Z","caller":"traceutil/trace.go:171","msg":"trace[855835818] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cb4d6a07-e52c-4e48-a831-d99d256df671; range_end:; response_count:1; response_revision:2178; }","duration":"298.934909ms","start":"2026-04-17T11:04:15.63053Z","end":"2026-04-17T11:04:15.929465Z","steps":["trace[855835818] 'agreement among raft nodes before linearized reading' (duration: 298.613966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:15.929697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.704294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-17T11:04:15.929661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.739524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-17T11:04:15.929817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.550971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T11:04:15.929846Z","caller":"traceutil/trace.go:171","msg":"trace[1738828610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2178; }","duration":"354.601012ms","start":"2026-04-17T11:04:15.575236Z","end":"2026-04-17T11:04:15.929837Z","steps":["trace[1738828610] 'agreement among raft nodes before linearized reading' (duration: 353.960054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:15.929889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:15.575224Z","time spent":"354.655582ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T11:04:15.929825Z","caller":"traceutil/trace.go:171","msg":"trace[1696597092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2178; }","duration":"372.333263ms","start":"2026-04-17T11:04:15.55747Z","end":"2026-04-17T11:04:15.929803Z","steps":["trace[1696597092] 'agreement among raft nodes before linearized reading' (duration: 371.754476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:15.929998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.108048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T11:04:15.930041Z","caller":"traceutil/trace.go:171","msg":"trace[10355624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2178; }","duration":"593.183719ms","start":"2026-04-17T11:04:15.336845Z","end":"2026-04-17T11:04:15.930029Z","steps":["trace[10355624] 'agreement among raft nodes before linearized reading' (duration: 592.381889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:15.930043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:15.557464Z","time spent":"372.564136ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T11:04:15.930082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:15.336827Z","time spent":"593.24806ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T11:04:15.929751Z","caller":"traceutil/trace.go:171","msg":"trace[517902026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2178; }","duration":"417.798175ms","start":"2026-04-17T11:04:15.51194Z","end":"2026-04-17T11:04:15.929738Z","steps":["trace[517902026] 'agreement among raft nodes before linearized reading' (duration: 417.46638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:15.930159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:15.511917Z","time spent":"418.222539ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T11:04:15.929143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.021187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-17T11:04:15.930334Z","caller":"traceutil/trace.go:171","msg":"trace[180799775] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2178; }","duration":"805.314232ms","start":"2026-04-17T11:04:15.124992Z","end":"2026-04-17T11:04:15.930306Z","steps":["trace[180799775] 'agreement among raft nodes before linearized reading' (duration: 697.524837ms)","trace[180799775] 'range keys from in-memory index tree' (duration: 106.152107ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:04:15.931976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:15.124971Z","time spent":"806.986249ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-17T11:04:16.096829Z","caller":"traceutil/trace.go:171","msg":"trace[932075270] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"161.922287ms","start":"2026-04-17T11:04:15.934876Z","end":"2026-04-17T11:04:16.096798Z","steps":["trace[932075270] 'process raft request' (duration: 149.240691ms)","trace[932075270] 'compare' (duration: 12.473714ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:04:16.096839Z","caller":"traceutil/trace.go:171","msg":"trace[1286100801] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"161.889776ms","start":"2026-04-17T11:04:15.934926Z","end":"2026-04-17T11:04:16.096816Z","steps":["trace[1286100801] 'process raft request' (duration: 161.805306ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:16.097277Z","caller":"traceutil/trace.go:171","msg":"trace[75102187] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"161.056147ms","start":"2026-04-17T11:04:15.936197Z","end":"2026-04-17T11:04:16.097253Z","steps":["trace[75102187] 'process raft request' (duration: 160.878275ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:16.097351Z","caller":"traceutil/trace.go:171","msg":"trace[86380753] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"162.159348ms","start":"2026-04-17T11:04:15.935179Z","end":"2026-04-17T11:04:16.097338Z","steps":["trace[86380753] 'process raft request' (duration: 161.595702ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:16.117792Z","caller":"traceutil/trace.go:171","msg":"trace[1440541277] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"154.85499ms","start":"2026-04-17T11:04:15.962918Z","end":"2026-04-17T11:04:16.117773Z","steps":["trace[1440541277] 'process raft request' (duration: 154.687758ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:16.118086Z","caller":"traceutil/trace.go:171","msg":"trace[475185411] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"119.449778ms","start":"2026-04-17T11:04:15.998543Z","end":"2026-04-17T11:04:16.117993Z","steps":["trace[475185411] 'process raft request' (duration: 119.183005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:17.116007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.749813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b8efb381-98a3-4b31-9b23-c9be46e5cee8\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-17T11:04:17.116079Z","caller":"traceutil/trace.go:171","msg":"trace[1202972676] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b8efb381-98a3-4b31-9b23-c9be46e5cee8; range_end:; response_count:1; response_revision:2185; }","duration":"180.868705ms","start":"2026-04-17T11:04:16.935196Z","end":"2026-04-17T11:04:17.116064Z","steps":["trace[1202972676] 'range keys from in-memory index tree' (duration: 180.590812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:17.116437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.001398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cb4d6a07-e52c-4e48-a831-d99d256df671\" ","response":"range_response_count:1 size:3737"} {"level":"warn","ts":"2026-04-17T11:04:17.116499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.05844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"info","ts":"2026-04-17T11:04:17.116553Z","caller":"traceutil/trace.go:171","msg":"trace[173580181] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cb4d6a07-e52c-4e48-a831-d99d256df671; range_end:; response_count:1; response_revision:2185; }","duration":"182.172369ms","start":"2026-04-17T11:04:16.934356Z","end":"2026-04-17T11:04:17.116528Z","steps":["trace[173580181] 'range keys from in-memory index tree' (duration: 181.687894ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:17.116569Z","caller":"traceutil/trace.go:171","msg":"trace[184656751] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2185; }","duration":"185.181661ms","start":"2026-04-17T11:04:16.93137Z","end":"2026-04-17T11:04:17.116552Z","steps":["trace[184656751] 'range keys from in-memory index tree' (duration: 184.628666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:17.117404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.379407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-17T11:04:17.1175Z","caller":"traceutil/trace.go:171","msg":"trace[1478580724] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2185; }","duration":"177.956974ms","start":"2026-04-17T11:04:16.939518Z","end":"2026-04-17T11:04:17.117475Z","steps":["trace[1478580724] 'range keys from in-memory index tree' (duration: 176.485848ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:28.277311Z","caller":"traceutil/trace.go:171","msg":"trace[1177732125] transaction","detail":"{read_only:false; response_revision:2304; number_of_response:1; }","duration":"106.187978ms","start":"2026-04-17T11:04:28.171087Z","end":"2026-04-17T11:04:28.277275Z","steps":["trace[1177732125] 'process raft request' (duration: 82.35977ms)","trace[1177732125] 'compare' (duration: 23.526665ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:04:28.277984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.028044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-17T11:04:28.278066Z","caller":"traceutil/trace.go:171","msg":"trace[1841424180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2305; }","duration":"102.132715ms","start":"2026-04-17T11:04:28.175915Z","end":"2026-04-17T11:04:28.278048Z","steps":["trace[1841424180] 'agreement among raft nodes before linearized reading' (duration: 101.929363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:28.277587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.48658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T11:04:28.278336Z","caller":"traceutil/trace.go:171","msg":"trace[2100975065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2305; }","duration":"109.26388ms","start":"2026-04-17T11:04:28.16905Z","end":"2026-04-17T11:04:28.278314Z","steps":["trace[2100975065] 'agreement among raft nodes before linearized reading' (duration: 108.3372ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:28.519015Z","caller":"traceutil/trace.go:171","msg":"trace[1949228315] transaction","detail":"{read_only:false; response_revision:2306; number_of_response:1; }","duration":"236.142404ms","start":"2026-04-17T11:04:28.282834Z","end":"2026-04-17T11:04:28.518976Z","steps":["trace[1949228315] 'process raft request' (duration: 189.315805ms)","trace[1949228315] 'compare' (duration: 46.672607ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:04:28.544406Z","caller":"traceutil/trace.go:171","msg":"trace[981130723] linearizableReadLoop","detail":"{readStateIndex:2403; appliedIndex:2400; }","duration":"132.659665ms","start":"2026-04-17T11:04:28.411729Z","end":"2026-04-17T11:04:28.544389Z","steps":["trace[981130723] 'read index received' (duration: 60.437261ms)","trace[981130723] 'applied index is now lower than readState.Index' (duration: 72.221384ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:04:28.544457Z","caller":"traceutil/trace.go:171","msg":"trace[229928310] transaction","detail":"{read_only:false; response_revision:2307; number_of_response:1; }","duration":"261.328137ms","start":"2026-04-17T11:04:28.283092Z","end":"2026-04-17T11:04:28.54442Z","steps":["trace[229928310] 'process raft request' (duration: 261.151835ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:28.544533Z","caller":"traceutil/trace.go:171","msg":"trace[689972839] transaction","detail":"{read_only:false; response_revision:2308; number_of_response:1; }","duration":"260.69858ms","start":"2026-04-17T11:04:28.283784Z","end":"2026-04-17T11:04:28.544482Z","steps":["trace[689972839] 'process raft request' (duration: 260.568298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:28.544664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.806876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T11:04:28.544755Z","caller":"traceutil/trace.go:171","msg":"trace[877936646] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2308; }","duration":"133.062888ms","start":"2026-04-17T11:04:28.411682Z","end":"2026-04-17T11:04:28.544745Z","steps":["trace[877936646] 'agreement among raft nodes before linearized reading' (duration: 132.801036ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:32.243628Z","caller":"traceutil/trace.go:171","msg":"trace[1810010010] transaction","detail":"{read_only:false; response_revision:2329; number_of_response:1; }","duration":"216.159922ms","start":"2026-04-17T11:04:32.027436Z","end":"2026-04-17T11:04:32.243596Z","steps":["trace[1810010010] 'process raft request' (duration: 215.828659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:36.756959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495438592188673,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T11:04:37.015801Z","caller":"traceutil/trace.go:171","msg":"trace[253075944] transaction","detail":"{read_only:false; response_revision:2362; number_of_response:1; }","duration":"760.225292ms","start":"2026-04-17T11:04:36.255548Z","end":"2026-04-17T11:04:37.015773Z","steps":["trace[253075944] 'process raft request' (duration: 760.095481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:37.015965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:36.255526Z","time spent":"760.343173ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:04:37.035442Z","caller":"traceutil/trace.go:171","msg":"trace[127879321] linearizableReadLoop","detail":"{readStateIndex:2462; appliedIndex:2459; }","duration":"778.842671ms","start":"2026-04-17T11:04:36.256583Z","end":"2026-04-17T11:04:37.035426Z","steps":["trace[127879321] 'read index received' (duration: 759.12315ms)","trace[127879321] 'applied index is now lower than readState.Index' (duration: 19.718731ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:04:37.035686Z","caller":"traceutil/trace.go:171","msg":"trace[1695360655] transaction","detail":"{read_only:false; response_revision:2363; number_of_response:1; }","duration":"733.185914ms","start":"2026-04-17T11:04:36.302492Z","end":"2026-04-17T11:04:37.035678Z","steps":["trace[1695360655] 'process raft request' (duration: 732.75594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:37.035771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:36.30247Z","time spent":"733.235385ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:04:37.035889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.363091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T11:04:37.03594Z","caller":"traceutil/trace.go:171","msg":"trace[310221862] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2364; }","duration":"710.458772ms","start":"2026-04-17T11:04:36.325468Z","end":"2026-04-17T11:04:37.035927Z","steps":["trace[310221862] 'agreement among raft nodes before linearized reading' (duration: 710.25787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:37.035976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:36.325448Z","time spent":"710.520592ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T11:04:37.036023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.896795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T11:04:37.036044Z","caller":"traceutil/trace.go:171","msg":"trace[2023418607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2364; }","duration":"450.926695ms","start":"2026-04-17T11:04:36.585111Z","end":"2026-04-17T11:04:37.036038Z","steps":["trace[2023418607] 'agreement among raft nodes before linearized reading' (duration: 450.860354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:37.036061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:36.585106Z","time spent":"450.950205ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-17T11:04:37.036046Z","caller":"traceutil/trace.go:171","msg":"trace[772809295] transaction","detail":"{read_only:false; response_revision:2364; number_of_response:1; }","duration":"389.788479ms","start":"2026-04-17T11:04:36.646225Z","end":"2026-04-17T11:04:37.036013Z","steps":["trace[772809295] 'process raft request' (duration: 389.173143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:37.036135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.554848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T11:04:37.036158Z","caller":"traceutil/trace.go:171","msg":"trace[545877501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2364; }","duration":"779.604329ms","start":"2026-04-17T11:04:36.25655Z","end":"2026-04-17T11:04:37.036154Z","steps":["trace[545877501] 'agreement among raft nodes before linearized reading' (duration: 779.549859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:37.036176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:36.256531Z","time spent":"779.639969ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T11:04:37.036176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:36.646204Z","time spent":"389.901501ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:04:37.036235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.511962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T11:04:37.036279Z","caller":"traceutil/trace.go:171","msg":"trace[1739975503] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2364; }","duration":"454.574742ms","start":"2026-04-17T11:04:36.581693Z","end":"2026-04-17T11:04:37.036268Z","steps":["trace[1739975503] 'agreement among raft nodes before linearized reading' (duration: 454.471561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:37.03631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:36.581679Z","time spent":"454.621093ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T11:04:37.036362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.519124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T11:04:37.03638Z","caller":"traceutil/trace.go:171","msg":"trace[779051928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2364; }","duration":"468.572795ms","start":"2026-04-17T11:04:36.567802Z","end":"2026-04-17T11:04:37.036375Z","steps":["trace[779051928] 'agreement among raft nodes before linearized reading' (duration: 468.526835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:37.036316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.052684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-17T11:04:37.036396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:36.567775Z","time spent":"468.616965ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-17T11:04:37.036408Z","caller":"traceutil/trace.go:171","msg":"trace[120727767] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2364; }","duration":"695.153904ms","start":"2026-04-17T11:04:36.341243Z","end":"2026-04-17T11:04:37.036397Z","steps":["trace[120727767] 'agreement among raft nodes before linearized reading' (duration: 695.031203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:37.036436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:36.341236Z","time spent":"695.193085ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-17T11:04:37.157533Z","caller":"traceutil/trace.go:171","msg":"trace[1363243429] transaction","detail":"{read_only:false; response_revision:2365; number_of_response:1; }","duration":"117.734052ms","start":"2026-04-17T11:04:37.039773Z","end":"2026-04-17T11:04:37.157507Z","steps":["trace[1363243429] 'process raft request' (duration: 115.846894ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:37.231125Z","caller":"traceutil/trace.go:171","msg":"trace[694486565] transaction","detail":"{read_only:false; response_revision:2367; number_of_response:1; }","duration":"191.228014ms","start":"2026-04-17T11:04:37.039886Z","end":"2026-04-17T11:04:37.231114Z","steps":["trace[694486565] 'process raft request' (duration: 191.180483ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:37.231187Z","caller":"traceutil/trace.go:171","msg":"trace[984091244] transaction","detail":"{read_only:false; response_revision:2366; number_of_response:1; }","duration":"191.264794ms","start":"2026-04-17T11:04:37.039856Z","end":"2026-04-17T11:04:37.231121Z","steps":["trace[984091244] 'process raft request' (duration: 191.159273ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:37.231375Z","caller":"traceutil/trace.go:171","msg":"trace[1292901437] transaction","detail":"{read_only:false; response_revision:2368; number_of_response:1; }","duration":"191.461656ms","start":"2026-04-17T11:04:37.039892Z","end":"2026-04-17T11:04:37.231354Z","steps":["trace[1292901437] 'process raft request' (duration: 191.196583ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:37.231884Z","caller":"traceutil/trace.go:171","msg":"trace[1700747214] transaction","detail":"{read_only:false; response_revision:2369; number_of_response:1; }","duration":"191.304274ms","start":"2026-04-17T11:04:37.040368Z","end":"2026-04-17T11:04:37.231672Z","steps":["trace[1700747214] 'process raft request' (duration: 190.839999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:45.802862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.544768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:04:45.802936Z","caller":"traceutil/trace.go:171","msg":"trace[696028431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2407; }","duration":"523.665559ms","start":"2026-04-17T11:04:45.279252Z","end":"2026-04-17T11:04:45.802918Z","steps":["trace[696028431] 'range keys from in-memory index tree' (duration: 523.472667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:45.803013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.458216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-17T11:04:45.803078Z","caller":"traceutil/trace.go:171","msg":"trace[843358753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2407; }","duration":"523.563488ms","start":"2026-04-17T11:04:45.279501Z","end":"2026-04-17T11:04:45.803065Z","steps":["trace[843358753] 'range keys from in-memory index tree' (duration: 523.314145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:45.803105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:45.279497Z","time spent":"523.602938ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T11:04:45.803322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.827352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-17T11:04:45.803339Z","caller":"traceutil/trace.go:171","msg":"trace[1234353490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2407; }","duration":"523.857202ms","start":"2026-04-17T11:04:45.279477Z","end":"2026-04-17T11:04:45.803335Z","steps":["trace[1234353490] 'range keys from in-memory index tree' (duration: 523.348275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:45.803352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:45.279472Z","time spent":"523.876802ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T11:04:45.803315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.813131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-17T11:04:45.803452Z","caller":"traceutil/trace.go:171","msg":"trace[688781647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2407; }","duration":"523.983633ms","start":"2026-04-17T11:04:45.279454Z","end":"2026-04-17T11:04:45.803438Z","steps":["trace[688781647] 'range keys from in-memory index tree' (duration: 523.624849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:45.803524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:45.279443Z","time spent":"524.068334ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T11:04:45.803544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.106244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-17T11:04:45.802987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:45.279232Z","time spent":"523.743839ms","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T11:04:45.803626Z","caller":"traceutil/trace.go:171","msg":"trace[172601194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2407; }","duration":"524.238585ms","start":"2026-04-17T11:04:45.279366Z","end":"2026-04-17T11:04:45.803605Z","steps":["trace[172601194] 'range keys from in-memory index tree' (duration: 523.866221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:45.803677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:45.279341Z","time spent":"524.327166ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T11:04:45.803109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.584979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-17T11:04:45.804224Z","caller":"traceutil/trace.go:171","msg":"trace[1023852335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2407; }","duration":"524.7628ms","start":"2026-04-17T11:04:45.279448Z","end":"2026-04-17T11:04:45.80421Z","steps":["trace[1023852335] 'range keys from in-memory index tree' (duration: 523.383136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:45.805158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:45.279437Z","time spent":"525.668069ms","remote":"127.0.0.1:44424","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":"info","ts":"2026-04-17T11:04:50.031465Z","caller":"traceutil/trace.go:171","msg":"trace[1048185335] linearizableReadLoop","detail":"{readStateIndex:2537; appliedIndex:2535; }","duration":"194.823281ms","start":"2026-04-17T11:04:49.836613Z","end":"2026-04-17T11:04:50.031436Z","steps":["trace[1048185335] 'read index received' (duration: 76.828906ms)","trace[1048185335] 'applied index is now lower than readState.Index' (duration: 117.992685ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:04:50.03153Z","caller":"traceutil/trace.go:171","msg":"trace[1517029347] transaction","detail":"{read_only:false; response_revision:2437; number_of_response:1; }","duration":"196.68526ms","start":"2026-04-17T11:04:49.834816Z","end":"2026-04-17T11:04:50.031501Z","steps":["trace[1517029347] 'process raft request' (duration: 182.151376ms)","trace[1517029347] 'compare' (duration: 14.346202ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:04:50.031887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.252565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T11:04:50.031937Z","caller":"traceutil/trace.go:171","msg":"trace[419519368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2437; }","duration":"195.364157ms","start":"2026-04-17T11:04:49.83656Z","end":"2026-04-17T11:04:50.031924Z","steps":["trace[419519368] 'agreement among raft nodes before linearized reading' (duration: 195.018654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:50.120917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.344412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T11:04:50.120989Z","caller":"traceutil/trace.go:171","msg":"trace[201091406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2440; }","duration":"280.497303ms","start":"2026-04-17T11:04:49.840477Z","end":"2026-04-17T11:04:50.120974Z","steps":["trace[201091406] 'agreement among raft nodes before linearized reading' (duration: 280.229001ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:50.121074Z","caller":"traceutil/trace.go:171","msg":"trace[228648386] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"101.157386ms","start":"2026-04-17T11:04:50.019899Z","end":"2026-04-17T11:04:50.121057Z","steps":["trace[228648386] 'process raft request' (duration: 100.58713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:50.121549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.255691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T11:04:50.121672Z","caller":"traceutil/trace.go:171","msg":"trace[812498011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2440; }","duration":"280.427023ms","start":"2026-04-17T11:04:49.841218Z","end":"2026-04-17T11:04:50.121645Z","steps":["trace[812498011] 'agreement among raft nodes before linearized reading' (duration: 280.064989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:50.123257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.497854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T11:04:50.123321Z","caller":"traceutil/trace.go:171","msg":"trace[816326787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2440; }","duration":"282.620675ms","start":"2026-04-17T11:04:49.840686Z","end":"2026-04-17T11:04:50.123307Z","steps":["trace[816326787] 'agreement among raft nodes before linearized reading' (duration: 280.668925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:50.32055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.874662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T11:04:50.320646Z","caller":"traceutil/trace.go:171","msg":"trace[2066685407] transaction","detail":"{read_only:false; response_revision:2441; number_of_response:1; }","duration":"194.140341ms","start":"2026-04-17T11:04:50.126485Z","end":"2026-04-17T11:04:50.320625Z","steps":["trace[2066685407] 'process raft request' (duration: 43.113828ms)","trace[2066685407] 'compare' (duration: 150.66382ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:04:50.355895Z","caller":"traceutil/trace.go:171","msg":"trace[817159712] transaction","detail":"{read_only:false; response_revision:2443; number_of_response:1; }","duration":"226.18901ms","start":"2026-04-17T11:04:50.129683Z","end":"2026-04-17T11:04:50.355872Z","steps":["trace[817159712] 'process raft request' (duration: 226.15413ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:50.355916Z","caller":"traceutil/trace.go:171","msg":"trace[742093604] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"226.299001ms","start":"2026-04-17T11:04:50.129588Z","end":"2026-04-17T11:04:50.355887Z","steps":["trace[742093604] 'process raft request' (duration: 226.12053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:51.388185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.604732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:04:51.388277Z","caller":"traceutil/trace.go:171","msg":"trace[1433222766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2446; }","duration":"188.749764ms","start":"2026-04-17T11:04:51.199507Z","end":"2026-04-17T11:04:51.388257Z","steps":["trace[1433222766] 'range keys from in-memory index tree' (duration: 188.467741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:52.498708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.280051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T11:04:52.499084Z","caller":"traceutil/trace.go:171","msg":"trace[473572917] linearizableReadLoop","detail":"{readStateIndex:2552; appliedIndex:2548; }","duration":"386.585402ms","start":"2026-04-17T11:04:52.112475Z","end":"2026-04-17T11:04:52.49906Z","steps":["trace[473572917] 'read index received' (duration: 135.845637ms)","trace[473572917] 'applied index is now lower than readState.Index' (duration: 250.738275ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:04:52.499184Z","caller":"traceutil/trace.go:171","msg":"trace[1635968532] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"571.492646ms","start":"2026-04-17T11:04:51.927679Z","end":"2026-04-17T11:04:52.499172Z","steps":["trace[1635968532] 'process raft request' (duration: 320.62801ms)","trace[1635968532] 'compare' (duration: 250.134159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:04:52.49926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:51.927665Z","time spent":"571.556957ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:04:52.49949Z","caller":"traceutil/trace.go:171","msg":"trace[296117891] transaction","detail":"{read_only:false; response_revision:2452; number_of_response:1; }","duration":"372.753544ms","start":"2026-04-17T11:04:52.126725Z","end":"2026-04-17T11:04:52.499478Z","steps":["trace[296117891] 'process raft request' (duration: 372.29279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:52.499561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:52.126708Z","time spent":"372.816466ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:04:52.499677Z","caller":"traceutil/trace.go:171","msg":"trace[593391486] transaction","detail":"{read_only:false; response_revision:2449; number_of_response:1; }","duration":"540.245926ms","start":"2026-04-17T11:04:51.959419Z","end":"2026-04-17T11:04:52.499665Z","steps":["trace[593391486] 'process raft request' (duration: 539.454539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:52.499815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:51.95939Z","time spent":"540.343407ms","remote":"127.0.0.1:44242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":805,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T11:04:52.49945Z","caller":"traceutil/trace.go:171","msg":"trace[6538871] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"441.281375ms","start":"2026-04-17T11:04:52.05813Z","end":"2026-04-17T11:04:52.499411Z","steps":["trace[6538871] 'process raft request' (duration: 440.850571ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:52.499944Z","caller":"traceutil/trace.go:171","msg":"trace[875775096] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"445.213454ms","start":"2026-04-17T11:04:52.054695Z","end":"2026-04-17T11:04:52.499908Z","steps":["trace[875775096] 'process raft request' (duration: 444.239404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:52.499684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.219508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:04:52.500081Z","caller":"traceutil/trace.go:171","msg":"trace[53067028] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2452; }","duration":"387.644312ms","start":"2026-04-17T11:04:52.11241Z","end":"2026-04-17T11:04:52.500054Z","steps":["trace[53067028] 'agreement among raft nodes before linearized reading' (duration: 387.235228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:52.500107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.318871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-17T11:04:52.50014Z","caller":"traceutil/trace.go:171","msg":"trace[184670700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2452; }","duration":"139.393692ms","start":"2026-04-17T11:04:52.360736Z","end":"2026-04-17T11:04:52.50013Z","steps":["trace[184670700] 'agreement among raft nodes before linearized reading' (duration: 139.306091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:52.500146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:52.112376Z","time spent":"387.744864ms","remote":"127.0.0.1:50094","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-04-17T11:04:52.500189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:52.054666Z","time spent":"445.384755ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:04:52.499828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.013227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-17T11:04:52.500015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:52.058099Z","time spent":"441.88292ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:04:52.500338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.675345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T11:04:52.500379Z","caller":"traceutil/trace.go:171","msg":"trace[494985567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2452; }","duration":"139.733345ms","start":"2026-04-17T11:04:52.360636Z","end":"2026-04-17T11:04:52.500369Z","steps":["trace[494985567] 'agreement among raft nodes before linearized reading' (duration: 139.644934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:52.500506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.657692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"info","ts":"2026-04-17T11:04:52.500538Z","caller":"traceutil/trace.go:171","msg":"trace[679370753] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2452; }","duration":"158.707963ms","start":"2026-04-17T11:04:52.341822Z","end":"2026-04-17T11:04:52.50053Z","steps":["trace[679370753] 'agreement among raft nodes before linearized reading' (duration: 158.624502ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:52.500311Z","caller":"traceutil/trace.go:171","msg":"trace[1847948448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2452; }","duration":"374.496491ms","start":"2026-04-17T11:04:52.125785Z","end":"2026-04-17T11:04:52.500281Z","steps":["trace[1847948448] 'agreement among raft nodes before linearized reading' (duration: 373.933386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:52.5007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.131426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T11:04:52.500754Z","caller":"traceutil/trace.go:171","msg":"trace[1879990218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2452; }","duration":"170.243427ms","start":"2026-04-17T11:04:52.3305Z","end":"2026-04-17T11:04:52.500743Z","steps":["trace[1879990218] 'agreement among raft nodes before linearized reading' (duration: 170.023064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:52.500731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:52.125773Z","time spent":"374.921375ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T11:04:52.500809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.511946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-17T11:04:52.50092Z","caller":"traceutil/trace.go:171","msg":"trace[1863856865] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2452; }","duration":"331.676557ms","start":"2026-04-17T11:04:52.169225Z","end":"2026-04-17T11:04:52.500902Z","steps":["trace[1863856865] 'agreement among raft nodes before linearized reading' (duration: 331.397334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:52.500983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:52.1692Z","time spent":"331.762688ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-17T11:04:52.684038Z","caller":"traceutil/trace.go:171","msg":"trace[110837059] linearizableReadLoop","detail":"{readStateIndex:2557; appliedIndex:2553; }","duration":"169.3841ms","start":"2026-04-17T11:04:52.514623Z","end":"2026-04-17T11:04:52.684007Z","steps":["trace[110837059] 'read index received' (duration: 22.859478ms)","trace[110837059] 'applied index is now lower than readState.Index' (duration: 146.523112ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:04:52.684304Z","caller":"traceutil/trace.go:171","msg":"trace[971786364] transaction","detail":"{read_only:false; response_revision:2456; number_of_response:1; }","duration":"175.870203ms","start":"2026-04-17T11:04:52.508386Z","end":"2026-04-17T11:04:52.684256Z","steps":["trace[971786364] 'process raft request' (duration: 175.56867ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:52.68432Z","caller":"traceutil/trace.go:171","msg":"trace[112127341] transaction","detail":"{read_only:false; response_revision:2454; number_of_response:1; }","duration":"176.130956ms","start":"2026-04-17T11:04:52.508166Z","end":"2026-04-17T11:04:52.684297Z","steps":["trace[112127341] 'process raft request' (duration: 106.9792ms)","trace[112127341] 'compare' (duration: 68.5879ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:04:52.684451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.790044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:04:52.684458Z","caller":"traceutil/trace.go:171","msg":"trace[1571513806] transaction","detail":"{read_only:false; response_revision:2455; number_of_response:1; }","duration":"176.170236ms","start":"2026-04-17T11:04:52.508279Z","end":"2026-04-17T11:04:52.684449Z","steps":["trace[1571513806] 'process raft request' (duration: 175.624981ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:52.684502Z","caller":"traceutil/trace.go:171","msg":"trace[1111463150] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2456; }","duration":"169.891374ms","start":"2026-04-17T11:04:52.514587Z","end":"2026-04-17T11:04:52.684479Z","steps":["trace[1111463150] 'agreement among raft nodes before linearized reading' (duration: 169.790343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:53.106282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.242272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T11:04:53.106434Z","caller":"traceutil/trace.go:171","msg":"trace[227190773] linearizableReadLoop","detail":"{readStateIndex:2558; appliedIndex:2557; }","duration":"411.184865ms","start":"2026-04-17T11:04:52.695234Z","end":"2026-04-17T11:04:53.106419Z","steps":["trace[227190773] 'read index received' (duration: 195.766161ms)","trace[227190773] 'applied index is now lower than readState.Index' (duration: 215.417034ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:04:53.106544Z","caller":"traceutil/trace.go:171","msg":"trace[1826112393] transaction","detail":"{read_only:false; response_revision:2457; number_of_response:1; }","duration":"415.815121ms","start":"2026-04-17T11:04:52.690718Z","end":"2026-04-17T11:04:53.106533Z","steps":["trace[1826112393] 'process raft request' (duration: 200.235355ms)","trace[1826112393] 'compare' (duration: 215.10921ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:04:53.106641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:52.690682Z","time spent":"415.933202ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:04:53.106857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.51692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T11:04:53.106941Z","caller":"traceutil/trace.go:171","msg":"trace[517189259] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2457; }","duration":"160.674251ms","start":"2026-04-17T11:04:52.946249Z","end":"2026-04-17T11:04:53.106923Z","steps":["trace[517189259] 'agreement among raft nodes before linearized reading' (duration: 160.426509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:53.106952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.71941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-17T11:04:53.107142Z","caller":"traceutil/trace.go:171","msg":"trace[620016390] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2457; }","duration":"411.882981ms","start":"2026-04-17T11:04:52.695197Z","end":"2026-04-17T11:04:53.10708Z","steps":["trace[620016390] 'agreement among raft nodes before linearized reading' (duration: 411.505257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:53.1072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:52.695183Z","time spent":"412.007663ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-17T11:04:53.959175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.005469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T11:04:53.95931Z","caller":"traceutil/trace.go:171","msg":"trace[1408864521] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"810.455249ms","start":"2026-04-17T11:04:53.148828Z","end":"2026-04-17T11:04:53.959283Z","steps":["trace[1408864521] 'process raft request' (duration: 329.229687ms)","trace[1408864521] 'compare' (duration: 480.794268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:04:53.959387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:53.148805Z","time spent":"810.543849ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:04:53.9837Z","caller":"traceutil/trace.go:171","msg":"trace[1570686203] linearizableReadLoop","detail":"{readStateIndex:2560; appliedIndex:2558; }","duration":"784.629343ms","start":"2026-04-17T11:04:53.199038Z","end":"2026-04-17T11:04:53.983668Z","steps":["trace[1570686203] 'read index received' (duration: 279.03521ms)","trace[1570686203] 'applied index is now lower than readState.Index' (duration: 505.592793ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:04:53.983752Z","caller":"traceutil/trace.go:171","msg":"trace[1589831170] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"813.230137ms","start":"2026-04-17T11:04:53.170487Z","end":"2026-04-17T11:04:53.983717Z","steps":["trace[1589831170] 'process raft request' (duration: 813.045875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:53.983923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:53.170461Z","time spent":"813.375879ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:04:53.983991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.17901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-17T11:04:53.984058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.695938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-17T11:04:53.984091Z","caller":"traceutil/trace.go:171","msg":"trace[1033833619] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2459; }","duration":"586.337511ms","start":"2026-04-17T11:04:53.397739Z","end":"2026-04-17T11:04:53.984077Z","steps":["trace[1033833619] 'agreement among raft nodes before linearized reading' (duration: 586.147279ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:04:53.98411Z","caller":"traceutil/trace.go:171","msg":"trace[1083466625] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2459; }","duration":"374.750998ms","start":"2026-04-17T11:04:53.609344Z","end":"2026-04-17T11:04:53.984095Z","steps":["trace[1083466625] 'agreement among raft nodes before linearized reading' (duration: 374.582967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:53.984148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:53.397719Z","time spent":"586.407672ms","remote":"127.0.0.1:44356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":20,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-17T11:04:53.984158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:53.609297Z","time spent":"374.842929ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-17T11:04:53.984361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.507014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-17T11:04:53.984477Z","caller":"traceutil/trace.go:171","msg":"trace[704404903] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2459; }","duration":"474.670326ms","start":"2026-04-17T11:04:53.509757Z","end":"2026-04-17T11:04:53.984427Z","steps":["trace[704404903] 'agreement among raft nodes before linearized reading' (duration: 474.252192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:53.984456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.424471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:04:53.984542Z","caller":"traceutil/trace.go:171","msg":"trace[1502672506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2459; }","duration":"785.541142ms","start":"2026-04-17T11:04:53.198989Z","end":"2026-04-17T11:04:53.98453Z","steps":["trace[1502672506] 'agreement among raft nodes before linearized reading' (duration: 784.858516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:04:53.98456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:53.509734Z","time spent":"474.785927ms","remote":"127.0.0.1:40598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-17T11:04:53.984579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:04:53.198972Z","time spent":"785.599603ms","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T11:04:54.193957Z","caller":"traceutil/trace.go:171","msg":"trace[936711395] transaction","detail":"{read_only:false; response_revision:2460; number_of_response:1; }","duration":"204.977364ms","start":"2026-04-17T11:04:53.988952Z","end":"2026-04-17T11:04:54.193929Z","steps":["trace[936711395] 'process raft request' (duration: 203.464799ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:05:20.151797Z","caller":"traceutil/trace.go:171","msg":"trace[29696060] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"102.287512ms","start":"2026-04-17T11:05:20.049488Z","end":"2026-04-17T11:05:20.151775Z","steps":["trace[29696060] 'process raft request' (duration: 102.111821ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:06:14.62367Z","caller":"traceutil/trace.go:171","msg":"trace[551178622] transaction","detail":"{read_only:false; response_revision:2965; number_of_response:1; }","duration":"234.688305ms","start":"2026-04-17T11:06:14.38895Z","end":"2026-04-17T11:06:14.623638Z","steps":["trace[551178622] 'process raft request' (duration: 234.487103ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:06:14.624862Z","caller":"traceutil/trace.go:171","msg":"trace[912948039] transaction","detail":"{read_only:false; response_revision:2966; number_of_response:1; }","duration":"206.369176ms","start":"2026-04-17T11:06:14.418465Z","end":"2026-04-17T11:06:14.624834Z","steps":["trace[912948039] 'process raft request' (duration: 206.249885ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:06:48.949783Z","caller":"traceutil/trace.go:171","msg":"trace[1206437888] transaction","detail":"{read_only:false; response_revision:3153; number_of_response:1; }","duration":"193.706527ms","start":"2026-04-17T11:06:48.756051Z","end":"2026-04-17T11:06:48.949757Z","steps":["trace[1206437888] 'process raft request' (duration: 193.544265ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:06:49.325359Z","caller":"traceutil/trace.go:171","msg":"trace[606720987] transaction","detail":"{read_only:false; response_revision:3154; number_of_response:1; }","duration":"564.613741ms","start":"2026-04-17T11:06:48.760703Z","end":"2026-04-17T11:06:49.325316Z","steps":["trace[606720987] 'process raft request' (duration: 467.393778ms)","trace[606720987] 'compare' (duration: 96.949239ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:06:49.325687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:06:48.760651Z","time spent":"564.918744ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:06:49.743277Z","caller":"traceutil/trace.go:171","msg":"trace[2035419259] linearizableReadLoop","detail":"{readStateIndex:3282; appliedIndex:3279; }","duration":"653.071874ms","start":"2026-04-17T11:06:49.090179Z","end":"2026-04-17T11:06:49.743251Z","steps":["trace[2035419259] 'read index received' (duration: 137.993746ms)","trace[2035419259] 'applied index is now lower than readState.Index' (duration: 515.076968ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:06:49.743445Z","caller":"traceutil/trace.go:171","msg":"trace[1051713418] transaction","detail":"{read_only:false; response_revision:3155; number_of_response:1; }","duration":"781.599014ms","start":"2026-04-17T11:06:48.961832Z","end":"2026-04-17T11:06:49.743431Z","steps":["trace[1051713418] 'process raft request' (duration: 781.14216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:06:49.743582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:06:48.96181Z","time spent":"781.673556ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:06:49.743669Z","caller":"traceutil/trace.go:171","msg":"trace[1289950545] transaction","detail":"{read_only:false; response_revision:3156; number_of_response:1; }","duration":"780.712687ms","start":"2026-04-17T11:06:48.962943Z","end":"2026-04-17T11:06:49.743656Z","steps":["trace[1289950545] 'process raft request' (duration: 780.256853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:06:49.743733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:06:48.962931Z","time spent":"780.778918ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:06:49.743935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.179366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T11:06:49.743962Z","caller":"traceutil/trace.go:171","msg":"trace[714222875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3156; }","duration":"643.302907ms","start":"2026-04-17T11:06:49.100653Z","end":"2026-04-17T11:06:49.743956Z","steps":["trace[714222875] 'agreement among raft nodes before linearized reading' (duration: 643.157936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:06:49.743979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:06:49.100624Z","time spent":"643.351157ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T11:06:49.744087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.664373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:06:49.744128Z","caller":"traceutil/trace.go:171","msg":"trace[1042225990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3156; }","duration":"544.740803ms","start":"2026-04-17T11:06:49.199378Z","end":"2026-04-17T11:06:49.744119Z","steps":["trace[1042225990] 'agreement among raft nodes before linearized reading' (duration: 544.677273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:06:49.744157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:06:49.199358Z","time spent":"544.792114ms","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-17T11:06:49.744214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.847935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-17T11:06:49.744285Z","caller":"traceutil/trace.go:171","msg":"trace[222517037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3156; }","duration":"476.946036ms","start":"2026-04-17T11:06:49.267323Z","end":"2026-04-17T11:06:49.744269Z","steps":["trace[222517037] 'agreement among raft nodes before linearized reading' (duration: 476.684823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:06:49.744282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.619152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T11:06:49.74432Z","caller":"traceutil/trace.go:171","msg":"trace[813879396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3156; }","duration":"478.699862ms","start":"2026-04-17T11:06:49.265615Z","end":"2026-04-17T11:06:49.744315Z","steps":["trace[813879396] 'agreement among raft nodes before linearized reading' (duration: 478.631172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:06:49.744326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:06:49.267317Z","time spent":"477.002376ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T11:06:49.744336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:06:49.26559Z","time spent":"478.741812ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T11:06:49.744454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.653663ms","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-04-17T11:06:49.744452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.602555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-17T11:06:49.744478Z","caller":"traceutil/trace.go:171","msg":"trace[973789477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3156; }","duration":"476.687823ms","start":"2026-04-17T11:06:49.267785Z","end":"2026-04-17T11:06:49.744472Z","steps":["trace[973789477] 'agreement among raft nodes before linearized reading' (duration: 476.629123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:06:49.744496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:06:49.267778Z","time spent":"476.714314ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-17T11:06:49.744493Z","caller":"traceutil/trace.go:171","msg":"trace[1129779351] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:3156; }","duration":"376.642996ms","start":"2026-04-17T11:06:49.367836Z","end":"2026-04-17T11:06:49.744479Z","steps":["trace[1129779351] 'agreement among raft nodes before linearized reading' (duration: 376.576675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:06:49.74453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:06:49.36775Z","time spent":"376.771117ms","remote":"127.0.0.1:44488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":6,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-17T11:06:49.744685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.458356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-17T11:06:49.744727Z","caller":"traceutil/trace.go:171","msg":"trace[1275487510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3156; }","duration":"654.603158ms","start":"2026-04-17T11:06:49.090118Z","end":"2026-04-17T11:06:49.744722Z","steps":["trace[1275487510] 'agreement among raft nodes before linearized reading' (duration: 654.462096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:06:49.744747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:06:49.090095Z","time spent":"654.648018ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-17T11:06:49.882635Z","caller":"traceutil/trace.go:171","msg":"trace[668564842] transaction","detail":"{read_only:false; response_revision:3157; number_of_response:1; }","duration":"123.521148ms","start":"2026-04-17T11:06:49.759088Z","end":"2026-04-17T11:06:49.88261Z","steps":["trace[668564842] 'process raft request' (duration: 72.328979ms)","trace[668564842] 'compare' (duration: 50.810205ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:06:49.895832Z","caller":"traceutil/trace.go:171","msg":"trace[487805961] transaction","detail":"{read_only:false; response_revision:3158; number_of_response:1; }","duration":"136.501393ms","start":"2026-04-17T11:06:49.759323Z","end":"2026-04-17T11:06:49.895824Z","steps":["trace[487805961] 'process raft request' (duration: 136.15743ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:06:49.896001Z","caller":"traceutil/trace.go:171","msg":"trace[1704265216] transaction","detail":"{read_only:false; response_revision:3161; number_of_response:1; }","duration":"135.563395ms","start":"2026-04-17T11:06:49.760427Z","end":"2026-04-17T11:06:49.895991Z","steps":["trace[1704265216] 'process raft request' (duration: 135.139311ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:06:49.896075Z","caller":"traceutil/trace.go:171","msg":"trace[1597393591] transaction","detail":"{read_only:false; response_revision:3159; number_of_response:1; }","duration":"136.677704ms","start":"2026-04-17T11:06:49.759357Z","end":"2026-04-17T11:06:49.896035Z","steps":["trace[1597393591] 'process raft request' (duration: 136.156949ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:06:49.896254Z","caller":"traceutil/trace.go:171","msg":"trace[1173148019] transaction","detail":"{read_only:false; response_revision:3160; number_of_response:1; }","duration":"136.867546ms","start":"2026-04-17T11:06:49.759369Z","end":"2026-04-17T11:06:49.896236Z","steps":["trace[1173148019] 'process raft request' (duration: 136.16717ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:08:43.593262Z","caller":"traceutil/trace.go:171","msg":"trace[212281282] transaction","detail":"{read_only:false; response_revision:3914; number_of_response:1; }","duration":"191.449327ms","start":"2026-04-17T11:08:43.401781Z","end":"2026-04-17T11:08:43.593231Z","steps":["trace[212281282] 'process raft request' (duration: 191.038344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:08:50.663401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.504036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T11:08:50.663471Z","caller":"traceutil/trace.go:171","msg":"trace[1824093235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3972; }","duration":"100.629576ms","start":"2026-04-17T11:08:50.562826Z","end":"2026-04-17T11:08:50.663456Z","steps":["trace[1824093235] 'range keys from in-memory index tree' (duration: 100.297064ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:09:29.058354Z","caller":"traceutil/trace.go:171","msg":"trace[1688910859] transaction","detail":"{read_only:false; response_revision:4309; number_of_response:1; }","duration":"145.131328ms","start":"2026-04-17T11:09:28.913193Z","end":"2026-04-17T11:09:29.058325Z","steps":["trace[1688910859] 'process raft request' (duration: 144.963306ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:09:29.134756Z","caller":"traceutil/trace.go:171","msg":"trace[798100624] linearizableReadLoop","detail":"{readStateIndex:4475; appliedIndex:4473; }","duration":"220.213177ms","start":"2026-04-17T11:09:28.914514Z","end":"2026-04-17T11:09:29.134727Z","steps":["trace[798100624] 'read index received' (duration: 143.695725ms)","trace[798100624] 'applied index is now lower than readState.Index' (duration: 76.516242ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:09:29.134963Z","caller":"traceutil/trace.go:171","msg":"trace[336768634] transaction","detail":"{read_only:false; response_revision:4310; number_of_response:1; }","duration":"221.6675ms","start":"2026-04-17T11:09:28.913257Z","end":"2026-04-17T11:09:29.134925Z","steps":["trace[336768634] 'process raft request' (duration: 221.332197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:09:29.135137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.691533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T11:09:29.135179Z","caller":"traceutil/trace.go:171","msg":"trace[832009575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4310; }","duration":"219.762863ms","start":"2026-04-17T11:09:28.915404Z","end":"2026-04-17T11:09:29.135167Z","steps":["trace[832009575] 'agreement among raft nodes before linearized reading' (duration: 219.606962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:09:29.135183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.951125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T11:09:29.135364Z","caller":"traceutil/trace.go:171","msg":"trace[2127017542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4310; }","duration":"220.166887ms","start":"2026-04-17T11:09:28.915176Z","end":"2026-04-17T11:09:29.135343Z","steps":["trace[2127017542] 'agreement among raft nodes before linearized reading' (duration: 219.824554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:09:29.135446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.936403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T11:09:29.135505Z","caller":"traceutil/trace.go:171","msg":"trace[293502098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4310; }","duration":"221.001114ms","start":"2026-04-17T11:09:28.914469Z","end":"2026-04-17T11:09:29.13547Z","steps":["trace[293502098] 'agreement among raft nodes before linearized reading' (duration: 220.421579ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:09:38.678997Z","caller":"traceutil/trace.go:171","msg":"trace[609185594] transaction","detail":"{read_only:false; response_revision:4372; number_of_response:1; }","duration":"142.993578ms","start":"2026-04-17T11:09:38.535971Z","end":"2026-04-17T11:09:38.678964Z","steps":["trace[609185594] 'process raft request' (duration: 142.736355ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:09:38.782823Z","caller":"traceutil/trace.go:171","msg":"trace[1880125239] transaction","detail":"{read_only:false; response_revision:4373; number_of_response:1; }","duration":"245.795691ms","start":"2026-04-17T11:09:38.53701Z","end":"2026-04-17T11:09:38.782806Z","steps":["trace[1880125239] 'process raft request' (duration: 245.68376ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:09:47.564779Z","caller":"traceutil/trace.go:171","msg":"trace[200432099] transaction","detail":"{read_only:false; response_revision:4506; number_of_response:1; }","duration":"137.436508ms","start":"2026-04-17T11:09:47.427288Z","end":"2026-04-17T11:09:47.564725Z","steps":["trace[200432099] 'process raft request' (duration: 136.669982ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:09:50.949614Z","caller":"traceutil/trace.go:171","msg":"trace[1479588109] transaction","detail":"{read_only:false; response_revision:4528; number_of_response:1; }","duration":"104.51147ms","start":"2026-04-17T11:09:50.845077Z","end":"2026-04-17T11:09:50.949588Z","steps":["trace[1479588109] 'process raft request' (duration: 104.356907ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:09:51.61598Z","caller":"traceutil/trace.go:171","msg":"trace[593590172] linearizableReadLoop","detail":"{readStateIndex:4704; appliedIndex:4703; }","duration":"263.284955ms","start":"2026-04-17T11:09:51.352674Z","end":"2026-04-17T11:09:51.615959Z","steps":["trace[593590172] 'read index received' (duration: 170.276668ms)","trace[593590172] 'applied index is now lower than readState.Index' (duration: 93.007407ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:09:51.617325Z","caller":"traceutil/trace.go:171","msg":"trace[821483915] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"277.578941ms","start":"2026-04-17T11:09:51.339704Z","end":"2026-04-17T11:09:51.617283Z","steps":["trace[821483915] 'process raft request' (duration: 183.20453ms)","trace[821483915] 'compare' (duration: 92.738176ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:09:51.617925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.237293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T11:09:51.617975Z","caller":"traceutil/trace.go:171","msg":"trace[1969426008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4530; }","duration":"265.336173ms","start":"2026-04-17T11:09:51.352627Z","end":"2026-04-17T11:09:51.617963Z","steps":["trace[1969426008] 'agreement among raft nodes before linearized reading' (duration: 265.175132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:09:51.626637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.898597ms","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-04-17T11:09:51.626687Z","caller":"traceutil/trace.go:171","msg":"trace[791466860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4531; }","duration":"266.963797ms","start":"2026-04-17T11:09:51.359711Z","end":"2026-04-17T11:09:51.626675Z","steps":["trace[791466860] 'agreement among raft nodes before linearized reading' (duration: 266.825976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:09:51.627396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.400216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T11:09:51.627447Z","caller":"traceutil/trace.go:171","msg":"trace[2117484471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4531; }","duration":"271.450396ms","start":"2026-04-17T11:09:51.355985Z","end":"2026-04-17T11:09:51.627435Z","steps":["trace[2117484471] 'agreement among raft nodes before linearized reading' (duration: 270.529429ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:09:52.094859Z","caller":"traceutil/trace.go:171","msg":"trace[1142555296] linearizableReadLoop","detail":"{readStateIndex:4709; appliedIndex:4708; }","duration":"138.035253ms","start":"2026-04-17T11:09:51.956771Z","end":"2026-04-17T11:09:52.094806Z","steps":["trace[1142555296] 'read index received' (duration: 137.786541ms)","trace[1142555296] 'applied index is now lower than readState.Index' (duration: 247.472µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:09:52.095073Z","caller":"traceutil/trace.go:171","msg":"trace[899409754] transaction","detail":"{read_only:false; response_revision:4535; number_of_response:1; }","duration":"152.644041ms","start":"2026-04-17T11:09:51.9424Z","end":"2026-04-17T11:09:52.095044Z","steps":["trace[899409754] 'process raft request' (duration: 152.220188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:09:52.095159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.347206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-haproxy\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:09:52.09525Z","caller":"traceutil/trace.go:171","msg":"trace[484026534] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-haproxy; range_end:; response_count:0; response_revision:4535; }","duration":"138.470647ms","start":"2026-04-17T11:09:51.956738Z","end":"2026-04-17T11:09:52.095209Z","steps":["trace[484026534] 'agreement among raft nodes before linearized reading' (duration: 138.225755ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:09:52.292113Z","caller":"traceutil/trace.go:171","msg":"trace[370010460] transaction","detail":"{read_only:false; response_revision:4536; number_of_response:1; }","duration":"170.136537ms","start":"2026-04-17T11:09:52.121951Z","end":"2026-04-17T11:09:52.292087Z","steps":["trace[370010460] 'process raft request' (duration: 169.711433ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:09:52.308002Z","caller":"traceutil/trace.go:171","msg":"trace[1265839980] transaction","detail":"{read_only:false; response_revision:4537; number_of_response:1; }","duration":"102.170628ms","start":"2026-04-17T11:09:52.205798Z","end":"2026-04-17T11:09:52.307969Z","steps":["trace[1265839980] 'process raft request' (duration: 101.824315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:09:59.235199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.573171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:27 size:40511"} {"level":"info","ts":"2026-04-17T11:09:59.235277Z","caller":"traceutil/trace.go:171","msg":"trace[1013108344] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:27; response_revision:4602; }","duration":"103.751042ms","start":"2026-04-17T11:09:59.131508Z","end":"2026-04-17T11:09:59.235259Z","steps":["trace[1013108344] 'agreement among raft nodes before linearized reading' (duration: 93.801975ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:27.09387Z","caller":"traceutil/trace.go:171","msg":"trace[140689811] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"104.357087ms","start":"2026-04-17T11:10:26.989481Z","end":"2026-04-17T11:10:27.093838Z","steps":["trace[140689811] 'process raft request' (duration: 86.950025ms)","trace[140689811] 'compare' (duration: 17.235731ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:10:27.107329Z","caller":"traceutil/trace.go:171","msg":"trace[388826032] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"108.111961ms","start":"2026-04-17T11:10:26.999169Z","end":"2026-04-17T11:10:27.10728Z","steps":["trace[388826032] 'process raft request' (duration: 107.845759ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:27.241308Z","caller":"traceutil/trace.go:171","msg":"trace[1371332531] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"125.942657ms","start":"2026-04-17T11:10:27.115337Z","end":"2026-04-17T11:10:27.241279Z","steps":["trace[1371332531] 'process raft request' (duration: 125.659475ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:27.288128Z","caller":"traceutil/trace.go:171","msg":"trace[474625194] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"168.801224ms","start":"2026-04-17T11:10:27.119299Z","end":"2026-04-17T11:10:27.2881Z","steps":["trace[474625194] 'process raft request' (duration: 168.521621ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:27.288308Z","caller":"traceutil/trace.go:171","msg":"trace[432899684] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"103.769412ms","start":"2026-04-17T11:10:27.184513Z","end":"2026-04-17T11:10:27.288283Z","steps":["trace[432899684] 'process raft request' (duration: 103.50376ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:27.349936Z","caller":"traceutil/trace.go:171","msg":"trace[455351503] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"103.779842ms","start":"2026-04-17T11:10:27.246121Z","end":"2026-04-17T11:10:27.349901Z","steps":["trace[455351503] 'process raft request' (duration: 97.474907ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:27.350263Z","caller":"traceutil/trace.go:171","msg":"trace[1239738476] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"103.757672ms","start":"2026-04-17T11:10:27.246476Z","end":"2026-04-17T11:10:27.350233Z","steps":["trace[1239738476] 'process raft request' (duration: 103.355228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:27.350523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.895791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:10:27.350563Z","caller":"traceutil/trace.go:171","msg":"trace[698916859] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:4833; }","duration":"146.992812ms","start":"2026-04-17T11:10:27.203559Z","end":"2026-04-17T11:10:27.350552Z","steps":["trace[698916859] 'agreement among raft nodes before linearized reading' (duration: 146.882531ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:27.350612Z","caller":"traceutil/trace.go:171","msg":"trace[827797959] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"102.866054ms","start":"2026-04-17T11:10:27.247737Z","end":"2026-04-17T11:10:27.350603Z","steps":["trace[827797959] 'process raft request' (duration: 102.39474ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:35.945809Z","caller":"traceutil/trace.go:171","msg":"trace[1850153994] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"104.105845ms","start":"2026-04-17T11:10:35.841632Z","end":"2026-04-17T11:10:35.945738Z","steps":["trace[1850153994] 'process raft request' (duration: 59.369032ms)","trace[1850153994] 'compare' (duration: 44.467201ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:10:37.692215Z","caller":"traceutil/trace.go:171","msg":"trace[2120922775] linearizableReadLoop","detail":"{readStateIndex:5458; appliedIndex:5457; }","duration":"142.434901ms","start":"2026-04-17T11:10:37.549759Z","end":"2026-04-17T11:10:37.692194Z","steps":["trace[2120922775] 'read index received' (duration: 142.242179ms)","trace[2120922775] 'applied index is now lower than readState.Index' (duration: 192.092µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:10:37.692528Z","caller":"traceutil/trace.go:171","msg":"trace[1960065744] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"188.160194ms","start":"2026-04-17T11:10:37.504355Z","end":"2026-04-17T11:10:37.692515Z","steps":["trace[1960065744] 'process raft request' (duration: 187.71511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:37.692809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.045698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T11:10:37.69284Z","caller":"traceutil/trace.go:171","msg":"trace[1689599000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5260; }","duration":"143.122348ms","start":"2026-04-17T11:10:37.54971Z","end":"2026-04-17T11:10:37.692832Z","steps":["trace[1689599000] 'agreement among raft nodes before linearized reading' (duration: 143.006887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:39.082409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.120862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T11:10:39.082541Z","caller":"traceutil/trace.go:171","msg":"trace[946561896] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"207.893748ms","start":"2026-04-17T11:10:38.87463Z","end":"2026-04-17T11:10:39.082524Z","steps":["trace[946561896] 'process raft request' (duration: 83.588584ms)","trace[946561896] 'compare' (duration: 124.026551ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:10:39.085857Z","caller":"traceutil/trace.go:171","msg":"trace[346177755] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"204.709449ms","start":"2026-04-17T11:10:38.881129Z","end":"2026-04-17T11:10:39.085838Z","steps":["trace[346177755] 'process raft request' (duration: 204.302415ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:40.410687Z","caller":"traceutil/trace.go:171","msg":"trace[2086342451] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"208.07958ms","start":"2026-04-17T11:10:40.202571Z","end":"2026-04-17T11:10:40.41065Z","steps":["trace[2086342451] 'process raft request' (duration: 191.127161ms)","trace[2086342451] 'compare' (duration: 16.578765ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:10:40.412742Z","caller":"traceutil/trace.go:171","msg":"trace[1765748595] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"206.364973ms","start":"2026-04-17T11:10:40.20635Z","end":"2026-04-17T11:10:40.412715Z","steps":["trace[1765748595] 'process raft request' (duration: 205.95864ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:40.412955Z","caller":"traceutil/trace.go:171","msg":"trace[448552489] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"206.475895ms","start":"2026-04-17T11:10:40.206429Z","end":"2026-04-17T11:10:40.412905Z","steps":["trace[448552489] 'process raft request' (duration: 206.0778ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:40.414555Z","caller":"traceutil/trace.go:171","msg":"trace[1670008273] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"205.967239ms","start":"2026-04-17T11:10:40.208555Z","end":"2026-04-17T11:10:40.414522Z","steps":["trace[1670008273] 'process raft request' (duration: 204.284005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:40.414749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.489563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1433"} {"level":"info","ts":"2026-04-17T11:10:40.414861Z","caller":"traceutil/trace.go:171","msg":"trace[91252273] range","detail":"{range_begin:/registry/clusterrolebindings/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5365; }","duration":"207.602785ms","start":"2026-04-17T11:10:40.207214Z","end":"2026-04-17T11:10:40.414817Z","steps":["trace[91252273] 'agreement among raft nodes before linearized reading' (duration: 207.394493ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:40.415734Z","caller":"traceutil/trace.go:171","msg":"trace[761907497] linearizableReadLoop","detail":"{readStateIndex:5561; appliedIndex:5558; }","duration":"206.632806ms","start":"2026-04-17T11:10:40.207233Z","end":"2026-04-17T11:10:40.413866Z","steps":["trace[761907497] 'read index received' (duration: 186.478389ms)","trace[761907497] 'applied index is now lower than readState.Index' (duration: 20.153347ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:10:45.218886Z","caller":"traceutil/trace.go:171","msg":"trace[1976340616] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"110.067497ms","start":"2026-04-17T11:10:45.108798Z","end":"2026-04-17T11:10:45.218865Z","steps":["trace[1976340616] 'process raft request' (duration: 109.626833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:52.353328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.942889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T11:10:52.353423Z","caller":"traceutil/trace.go:171","msg":"trace[432698162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5517; }","duration":"421.08743ms","start":"2026-04-17T11:10:51.932319Z","end":"2026-04-17T11:10:52.353406Z","steps":["trace[432698162] 'range keys from in-memory index tree' (duration: 420.785057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:52.353466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:51.932297Z","time spent":"421.15824ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-17T11:10:52.353568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.41157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-04-17T11:10:52.353646Z","caller":"traceutil/trace.go:171","msg":"trace[1743874029] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5517; }","duration":"308.544332ms","start":"2026-04-17T11:10:52.045082Z","end":"2026-04-17T11:10:52.353627Z","steps":["trace[1743874029] 'range keys from in-memory index tree' (duration: 308.250169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:52.353693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:52.045061Z","time spent":"308.623262ms","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":698,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-04-17T11:10:52.353715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.493815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-d5866d8df-2nplz.18a72071cffb500a\" ","response":"range_response_count:1 size:895"} {"level":"info","ts":"2026-04-17T11:10:52.353767Z","caller":"traceutil/trace.go:171","msg":"trace[21200949] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-d5866d8df-2nplz.18a72071cffb500a; range_end:; response_count:1; response_revision:5517; }","duration":"123.585916ms","start":"2026-04-17T11:10:52.230166Z","end":"2026-04-17T11:10:52.353751Z","steps":["trace[21200949] 'range keys from in-memory index tree' (duration: 123.380594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:52.353905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.789076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:10:52.353981Z","caller":"traceutil/trace.go:171","msg":"trace[1750801418] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:5517; }","duration":"404.964868ms","start":"2026-04-17T11:10:51.949005Z","end":"2026-04-17T11:10:52.353969Z","steps":["trace[1750801418] 'count revisions from in-memory index tree' (duration: 404.706896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:52.354019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:51.948992Z","time spent":"405.018519ms","remote":"127.0.0.1:44298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-17T11:10:52.354482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.96108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12974"} {"level":"info","ts":"2026-04-17T11:10:52.354562Z","caller":"traceutil/trace.go:171","msg":"trace[340159146] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5517; }","duration":"124.086331ms","start":"2026-04-17T11:10:52.230456Z","end":"2026-04-17T11:10:52.354543Z","steps":["trace[340159146] 'range keys from in-memory index tree' (duration: 122.740739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:52.858132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495438592196130,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T11:10:52.983456Z","caller":"traceutil/trace.go:171","msg":"trace[557390293] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"625.820429ms","start":"2026-04-17T11:10:52.357612Z","end":"2026-04-17T11:10:52.983432Z","steps":["trace[557390293] 'process raft request' (duration: 625.643498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:52.98379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:52.357591Z","time spent":"626.028252ms","remote":"127.0.0.1:44242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":880,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:10:53.01836Z","caller":"traceutil/trace.go:171","msg":"trace[1494486312] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"658.830289ms","start":"2026-04-17T11:10:52.359508Z","end":"2026-04-17T11:10:53.018338Z","steps":["trace[1494486312] 'process raft request' (duration: 658.524747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.018493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:52.359488Z","time spent":"658.92774ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:10:53.018654Z","caller":"traceutil/trace.go:171","msg":"trace[313758511] transaction","detail":"{read_only:false; response_revision:5521; number_of_response:1; }","duration":"554.661944ms","start":"2026-04-17T11:10:52.463944Z","end":"2026-04-17T11:10:53.018606Z","steps":["trace[313758511] 'process raft request' (duration: 554.325351ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:53.018385Z","caller":"traceutil/trace.go:171","msg":"trace[1773147769] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"653.666634ms","start":"2026-04-17T11:10:52.364694Z","end":"2026-04-17T11:10:53.01836Z","steps":["trace[1773147769] 'process raft request' (duration: 653.515952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.019031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:52.46393Z","time spent":"554.873836ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:10:53.01916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:52.364681Z","time spent":"654.3659ms","remote":"127.0.0.1:44354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:10:53.020613Z","caller":"traceutil/trace.go:171","msg":"trace[151765906] transaction","detail":"{read_only:false; response_revision:5522; number_of_response:1; }","duration":"460.814178ms","start":"2026-04-17T11:10:52.559774Z","end":"2026-04-17T11:10:53.020588Z","steps":["trace[151765906] 'process raft request' (duration: 458.661439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.020759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:52.559747Z","time spent":"460.94044ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:10:53.020997Z","caller":"traceutil/trace.go:171","msg":"trace[1856615847] linearizableReadLoop","detail":"{readStateIndex:5729; appliedIndex:5724; }","duration":"663.119427ms","start":"2026-04-17T11:10:52.357846Z","end":"2026-04-17T11:10:53.020966Z","steps":["trace[1856615847] 'read index received' (duration: 625.424876ms)","trace[1856615847] 'applied index is now lower than readState.Index' (duration: 37.692421ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:10:53.021301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.536892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-17T11:10:53.021356Z","caller":"traceutil/trace.go:171","msg":"trace[1934057746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5523; }","duration":"546.631324ms","start":"2026-04-17T11:10:52.474705Z","end":"2026-04-17T11:10:53.021337Z","steps":["trace[1934057746] 'agreement among raft nodes before linearized reading' (duration: 546.496293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.021388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:52.474695Z","time spent":"546.685664ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-17T11:10:53.021394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.434899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T11:10:53.021436Z","caller":"traceutil/trace.go:171","msg":"trace[1396374258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5523; }","duration":"532.49884ms","start":"2026-04-17T11:10:52.488926Z","end":"2026-04-17T11:10:53.021425Z","steps":["trace[1396374258] 'agreement among raft nodes before linearized reading' (duration: 532.336938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.021476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:52.488916Z","time spent":"532.55093ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T11:10:53.021508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.63639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T11:10:53.021543Z","caller":"traceutil/trace.go:171","msg":"trace[859943203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5523; }","duration":"532.687701ms","start":"2026-04-17T11:10:52.488847Z","end":"2026-04-17T11:10:53.021535Z","steps":["trace[859943203] 'agreement among raft nodes before linearized reading' (duration: 532.6066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.021574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:52.488838Z","time spent":"532.731143ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T11:10:53.021603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.865729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-data-valkey-node-1.18a7204833411fbc\" ","response":"range_response_count:1 size:764"} {"level":"info","ts":"2026-04-17T11:10:53.02163Z","caller":"traceutil/trace.go:171","msg":"trace[58916690] range","detail":"{range_begin:/registry/events/openstack/valkey-data-valkey-node-1.18a7204833411fbc; range_end:; response_count:1; response_revision:5523; }","duration":"519.910039ms","start":"2026-04-17T11:10:52.50171Z","end":"2026-04-17T11:10:53.02162Z","steps":["trace[58916690] 'agreement among raft nodes before linearized reading' (duration: 519.830748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.021663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:52.5017Z","time spent":"519.957299ms","remote":"127.0.0.1:44242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":787,"request content":"key:\"/registry/events/openstack/valkey-data-valkey-node-1.18a7204833411fbc\" "} {"level":"warn","ts":"2026-04-17T11:10:53.021688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.525968ms","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-04-17T11:10:53.021719Z","caller":"traceutil/trace.go:171","msg":"trace[981106809] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5523; }","duration":"542.575888ms","start":"2026-04-17T11:10:52.479136Z","end":"2026-04-17T11:10:53.021712Z","steps":["trace[981106809] 'agreement among raft nodes before linearized reading' (duration: 542.500317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.021691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.829923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-31f9840c-8d80-4b9f-88ce-c5cae1030248\" ","response":"range_response_count:1 size:1450"} {"level":"warn","ts":"2026-04-17T11:10:53.021747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:52.479126Z","time spent":"542.614938ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T11:10:53.021771Z","caller":"traceutil/trace.go:171","msg":"trace[2144549368] range","detail":"{range_begin:/registry/persistentvolumes/pvc-31f9840c-8d80-4b9f-88ce-c5cae1030248; range_end:; response_count:1; response_revision:5523; }","duration":"663.956904ms","start":"2026-04-17T11:10:52.357794Z","end":"2026-04-17T11:10:53.021751Z","steps":["trace[2144549368] 'agreement among raft nodes before linearized reading' (duration: 663.692961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.021824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:52.357775Z","time spent":"664.040485ms","remote":"127.0.0.1:44314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1473,"request content":"key:\"/registry/persistentvolumes/pvc-31f9840c-8d80-4b9f-88ce-c5cae1030248\" "} {"level":"warn","ts":"2026-04-17T11:10:53.324162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.410852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:10:53.324319Z","caller":"traceutil/trace.go:171","msg":"trace[240085749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5533; }","duration":"126.603083ms","start":"2026-04-17T11:10:53.197695Z","end":"2026-04-17T11:10:53.324298Z","steps":["trace[240085749] 'range keys from in-memory index tree' (duration: 126.26ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.765552Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305495438592196150,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-17T11:10:53.857295Z","caller":"traceutil/trace.go:171","msg":"trace[1594565538] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"603.747456ms","start":"2026-04-17T11:10:53.253523Z","end":"2026-04-17T11:10:53.85727Z","steps":["trace[1594565538] 'process raft request' (duration: 603.683616ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:53.857352Z","caller":"traceutil/trace.go:171","msg":"trace[1777309985] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"603.812916ms","start":"2026-04-17T11:10:53.253507Z","end":"2026-04-17T11:10:53.85732Z","steps":["trace[1777309985] 'process raft request' (duration: 603.469044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.857476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:53.253503Z","time spent":"603.877187ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:10:53.857508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:53.253482Z","time spent":"603.960678ms","remote":"127.0.0.1:44242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T11:10:53.860338Z","caller":"traceutil/trace.go:171","msg":"trace[1010130009] linearizableReadLoop","detail":"{readStateIndex:5743; appliedIndex:5740; }","duration":"595.661285ms","start":"2026-04-17T11:10:53.264646Z","end":"2026-04-17T11:10:53.860308Z","steps":["trace[1010130009] 'read index received' (duration: 592.386136ms)","trace[1010130009] 'applied index is now lower than readState.Index' (duration: 3.272989ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:10:53.860659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.000167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16086"} {"level":"info","ts":"2026-04-17T11:10:53.860716Z","caller":"traceutil/trace.go:171","msg":"trace[309186764] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5536; }","duration":"596.090278ms","start":"2026-04-17T11:10:53.264611Z","end":"2026-04-17T11:10:53.860702Z","steps":["trace[309186764] 'agreement among raft nodes before linearized reading' (duration: 595.869966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.86075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:53.264593Z","time spent":"596.149908ms","remote":"127.0.0.1:44354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16109,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-04-17T11:10:53.860771Z","caller":"traceutil/trace.go:171","msg":"trace[2122607755] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"401.73973ms","start":"2026-04-17T11:10:53.459008Z","end":"2026-04-17T11:10:53.860748Z","steps":["trace[2122607755] 'process raft request' (duration: 401.145235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.860813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.272404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T11:10:53.860898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:53.458987Z","time spent":"401.825101ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T11:10:53.861139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.581366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-17T11:10:53.861156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.734461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-17T11:10:53.861182Z","caller":"traceutil/trace.go:171","msg":"trace[716876533] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5536; }","duration":"502.682777ms","start":"2026-04-17T11:10:53.358489Z","end":"2026-04-17T11:10:53.861171Z","steps":["trace[716876533] 'agreement among raft nodes before linearized reading' (duration: 502.513955ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:53.861212Z","caller":"traceutil/trace.go:171","msg":"trace[538369822] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5536; }","duration":"524.805561ms","start":"2026-04-17T11:10:53.336374Z","end":"2026-04-17T11:10:53.86118Z","steps":["trace[538369822] 'agreement among raft nodes before linearized reading' (duration: 524.71049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.861216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:53.358477Z","time spent":"502.732087ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-17T11:10:53.861254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:53.336315Z","time spent":"524.930763ms","remote":"127.0.0.1:44316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-04-17T11:10:53.861544Z","caller":"traceutil/trace.go:171","msg":"trace[1259807623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5536; }","duration":"535.373084ms","start":"2026-04-17T11:10:53.325496Z","end":"2026-04-17T11:10:53.86087Z","steps":["trace[1259807623] 'agreement among raft nodes before linearized reading' (duration: 535.269943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:53.86162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:10:53.325483Z","time spent":"536.127721ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-17T11:10:54.135873Z","caller":"traceutil/trace.go:171","msg":"trace[954277565] transaction","detail":"{read_only:false; response_revision:5539; number_of_response:1; }","duration":"177.090156ms","start":"2026-04-17T11:10:53.958758Z","end":"2026-04-17T11:10:54.135848Z","steps":["trace[954277565] 'process raft request' (duration: 176.932184ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:54.142936Z","caller":"traceutil/trace.go:171","msg":"trace[1255169336] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"183.807506ms","start":"2026-04-17T11:10:53.959096Z","end":"2026-04-17T11:10:54.142904Z","steps":["trace[1255169336] 'process raft request' (duration: 181.470505ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:54.143524Z","caller":"traceutil/trace.go:171","msg":"trace[1366686882] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"184.106118ms","start":"2026-04-17T11:10:53.959405Z","end":"2026-04-17T11:10:54.143511Z","steps":["trace[1366686882] 'process raft request' (duration: 184.016407ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:54.143567Z","caller":"traceutil/trace.go:171","msg":"trace[204322517] transaction","detail":"{read_only:false; response_revision:5541; number_of_response:1; }","duration":"184.439421ms","start":"2026-04-17T11:10:53.9591Z","end":"2026-04-17T11:10:54.143539Z","steps":["trace[204322517] 'process raft request' (duration: 184.166689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:10:55.810492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.232767ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-17T11:10:57.545559Z","caller":"traceutil/trace.go:171","msg":"trace[1498422798] transaction","detail":"{read_only:false; response_revision:5569; number_of_response:1; }","duration":"228.455897ms","start":"2026-04-17T11:10:57.317072Z","end":"2026-04-17T11:10:57.545528Z","steps":["trace[1498422798] 'process raft request' (duration: 228.268615ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:10:57.565075Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2838} {"level":"info","ts":"2026-04-17T11:10:57.694127Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2838,"took":"127.6055ms","hash":4121564408,"current-db-size-bytes":53022720,"current-db-size":"53 MB","current-db-size-in-use-bytes":30556160,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-17T11:10:57.694207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4121564408,"revision":2838,"compact-revision":-1} {"level":"warn","ts":"2026-04-17T11:11:00.860299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.87926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-04-17T11:11:00.860355Z","caller":"traceutil/trace.go:171","msg":"trace[184837973] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5596; }","duration":"485.984201ms","start":"2026-04-17T11:11:00.374356Z","end":"2026-04-17T11:11:00.86034Z","steps":["trace[184837973] 'range keys from in-memory index tree' (duration: 485.755939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:11:00.860379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:11:00.374332Z","time spent":"486.041511ms","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":698,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-04-17T11:11:00.860299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.799235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-17T11:11:00.860486Z","caller":"traceutil/trace.go:171","msg":"trace[1119204684] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:5596; }","duration":"399.019956ms","start":"2026-04-17T11:11:00.461458Z","end":"2026-04-17T11:11:00.860478Z","steps":["trace[1119204684] 'range keys from in-memory index tree' (duration: 398.698964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:11:00.860501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:11:00.461441Z","time spent":"399.056327ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"info","ts":"2026-04-17T11:11:00.860755Z","caller":"traceutil/trace.go:171","msg":"trace[317140534] transaction","detail":"{read_only:false; response_revision:5597; number_of_response:1; }","duration":"329.780177ms","start":"2026-04-17T11:11:00.530956Z","end":"2026-04-17T11:11:00.860736Z","steps":["trace[317140534] 'process raft request' (duration: 270.970061ms)","trace[317140534] 'compare' (duration: 58.138621ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:11:00.860855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T11:11:00.530936Z","time spent":"329.854059ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T11:11:01.032138Z","caller":"traceutil/trace.go:171","msg":"trace[200993044] transaction","detail":"{read_only:false; response_revision:5598; number_of_response:1; }","duration":"150.881626ms","start":"2026-04-17T11:11:00.881219Z","end":"2026-04-17T11:11:01.032101Z","steps":["trace[200993044] 'process raft request' (duration: 148.105011ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:11:01.047703Z","caller":"traceutil/trace.go:171","msg":"trace[1417058058] transaction","detail":"{read_only:false; response_revision:5599; number_of_response:1; }","duration":"159.821544ms","start":"2026-04-17T11:11:00.887851Z","end":"2026-04-17T11:11:01.047673Z","steps":["trace[1417058058] 'process raft request' (duration: 159.592462ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:11:01.629686Z","caller":"traceutil/trace.go:171","msg":"trace[1336972304] transaction","detail":"{read_only:false; response_revision:5616; number_of_response:1; }","duration":"114.413704ms","start":"2026-04-17T11:11:01.515242Z","end":"2026-04-17T11:11:01.629656Z","steps":["trace[1336972304] 'process raft request' (duration: 114.215233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:11:03.53528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.321298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T11:11:03.53552Z","caller":"traceutil/trace.go:171","msg":"trace[1366285927] transaction","detail":"{read_only:false; response_revision:5647; number_of_response:1; }","duration":"224.988297ms","start":"2026-04-17T11:11:03.310513Z","end":"2026-04-17T11:11:03.535501Z","steps":["trace[1366285927] 'process raft request' (duration: 224.892337ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:11:03.53553Z","caller":"traceutil/trace.go:171","msg":"trace[1005660715] transaction","detail":"{read_only:false; response_revision:5646; number_of_response:1; }","duration":"225.25862ms","start":"2026-04-17T11:11:03.310237Z","end":"2026-04-17T11:11:03.535495Z","steps":["trace[1005660715] 'process raft request' (duration: 30.6125ms)","trace[1005660715] 'compare' (duration: 194.172346ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:11:03.535798Z","caller":"traceutil/trace.go:171","msg":"trace[12500654] transaction","detail":"{read_only:false; response_revision:5648; number_of_response:1; }","duration":"224.883525ms","start":"2026-04-17T11:11:03.310865Z","end":"2026-04-17T11:11:03.535749Z","steps":["trace[12500654] 'process raft request' (duration: 224.598283ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:11:03.993799Z","caller":"traceutil/trace.go:171","msg":"trace[363465055] transaction","detail":"{read_only:false; response_revision:5649; number_of_response:1; }","duration":"215.727585ms","start":"2026-04-17T11:11:03.778043Z","end":"2026-04-17T11:11:03.993771Z","steps":["trace[363465055] 'process raft request' (duration: 215.583024ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:11:09.033899Z","caller":"traceutil/trace.go:171","msg":"trace[1239445250] transaction","detail":"{read_only:false; response_revision:5698; number_of_response:1; }","duration":"133.2582ms","start":"2026-04-17T11:11:08.900602Z","end":"2026-04-17T11:11:09.03386Z","steps":["trace[1239445250] 'process raft request' (duration: 133.027518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:12:01.245273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.016728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:12:01.245346Z","caller":"traceutil/trace.go:171","msg":"trace[1621260871] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:6015; }","duration":"183.13854ms","start":"2026-04-17T11:12:01.062195Z","end":"2026-04-17T11:12:01.245334Z","steps":["trace[1621260871] 'count revisions from in-memory index tree' (duration: 182.944348ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:01.715971Z","caller":"traceutil/trace.go:171","msg":"trace[1572120477] transaction","detail":"{read_only:false; response_revision:6017; number_of_response:1; }","duration":"128.164396ms","start":"2026-04-17T11:12:01.587772Z","end":"2026-04-17T11:12:01.715937Z","steps":["trace[1572120477] 'process raft request' (duration: 127.955763ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:01.716652Z","caller":"traceutil/trace.go:171","msg":"trace[1971448440] linearizableReadLoop","detail":"{readStateIndex:6243; appliedIndex:6243; }","duration":"109.533613ms","start":"2026-04-17T11:12:01.606842Z","end":"2026-04-17T11:12:01.716376Z","steps":["trace[1971448440] 'read index received' (duration: 109.524193ms)","trace[1971448440] 'applied index is now lower than readState.Index' (duration: 7.65µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:12:01.717171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.959846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T11:12:01.717291Z","caller":"traceutil/trace.go:171","msg":"trace[1274482969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6017; }","duration":"110.5196ms","start":"2026-04-17T11:12:01.606743Z","end":"2026-04-17T11:12:01.717263Z","steps":["trace[1274482969] 'agreement among raft nodes before linearized reading' (duration: 109.759644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:12:18.450345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.003456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:12:18.45047Z","caller":"traceutil/trace.go:171","msg":"trace[1758910787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6142; }","duration":"250.173447ms","start":"2026-04-17T11:12:18.200276Z","end":"2026-04-17T11:12:18.45045Z","steps":["trace[1758910787] 'range keys from in-memory index tree' (duration: 249.919475ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:18.451435Z","caller":"traceutil/trace.go:171","msg":"trace[1317573700] transaction","detail":"{read_only:false; response_revision:6143; number_of_response:1; }","duration":"148.98178ms","start":"2026-04-17T11:12:18.302398Z","end":"2026-04-17T11:12:18.45138Z","steps":["trace[1317573700] 'process raft request' (duration: 143.768294ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:18.456066Z","caller":"traceutil/trace.go:171","msg":"trace[1899816567] transaction","detail":"{read_only:false; response_revision:6144; number_of_response:1; }","duration":"146.015753ms","start":"2026-04-17T11:12:18.310024Z","end":"2026-04-17T11:12:18.45604Z","steps":["trace[1899816567] 'process raft request' (duration: 145.71913ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:24.60051Z","caller":"traceutil/trace.go:171","msg":"trace[839718084] transaction","detail":"{read_only:false; response_revision:6177; number_of_response:1; }","duration":"119.48109ms","start":"2026-04-17T11:12:24.481001Z","end":"2026-04-17T11:12:24.600482Z","steps":["trace[839718084] 'process raft request' (duration: 82.866728ms)","trace[839718084] 'compare' (duration: 36.48541ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:12:25.713973Z","caller":"traceutil/trace.go:171","msg":"trace[1532403988] transaction","detail":"{read_only:false; response_revision:6192; number_of_response:1; }","duration":"113.142554ms","start":"2026-04-17T11:12:25.600795Z","end":"2026-04-17T11:12:25.713937Z","steps":["trace[1532403988] 'process raft request' (duration: 100.044489ms)","trace[1532403988] 'compare' (duration: 12.891884ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:12:25.714375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.898365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-17T11:12:25.715204Z","caller":"traceutil/trace.go:171","msg":"trace[519441949] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:6192; }","duration":"127.805954ms","start":"2026-04-17T11:12:25.587371Z","end":"2026-04-17T11:12:25.715177Z","steps":["trace[519441949] 'agreement among raft nodes before linearized reading' (duration: 126.570092ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:26.002149Z","caller":"traceutil/trace.go:171","msg":"trace[187712126] linearizableReadLoop","detail":"{readStateIndex:6439; appliedIndex:6438; }","duration":"166.20596ms","start":"2026-04-17T11:12:25.835895Z","end":"2026-04-17T11:12:26.002101Z","steps":["trace[187712126] 'read index received' (duration: 84.083579ms)","trace[187712126] 'applied index is now lower than readState.Index' (duration: 82.121041ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:12:26.002203Z","caller":"traceutil/trace.go:171","msg":"trace[888116933] transaction","detail":"{read_only:false; response_revision:6205; number_of_response:1; }","duration":"166.22747ms","start":"2026-04-17T11:12:25.835924Z","end":"2026-04-17T11:12:26.002152Z","steps":["trace[888116933] 'process raft request' (duration: 166.106799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:12:26.002556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.620933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-17T11:12:26.002588Z","caller":"traceutil/trace.go:171","msg":"trace[1674374092] transaction","detail":"{read_only:false; response_revision:6204; number_of_response:1; }","duration":"167.576453ms","start":"2026-04-17T11:12:25.834982Z","end":"2026-04-17T11:12:26.002558Z","steps":["trace[1674374092] 'process raft request' (duration: 127.969155ms)","trace[1674374092] 'compare' (duration: 38.877831ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:12:26.00265Z","caller":"traceutil/trace.go:171","msg":"trace[1089326573] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:6205; }","duration":"166.717974ms","start":"2026-04-17T11:12:25.835882Z","end":"2026-04-17T11:12:26.0026Z","steps":["trace[1089326573] 'agreement among raft nodes before linearized reading' (duration: 166.396421ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:26.635382Z","caller":"traceutil/trace.go:171","msg":"trace[1640233241] transaction","detail":"{read_only:false; response_revision:6249; number_of_response:1; }","duration":"107.322832ms","start":"2026-04-17T11:12:26.528034Z","end":"2026-04-17T11:12:26.635357Z","steps":["trace[1640233241] 'process raft request' (duration: 107.244861ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:26.635533Z","caller":"traceutil/trace.go:171","msg":"trace[1231436468] transaction","detail":"{read_only:false; response_revision:6248; number_of_response:1; }","duration":"145.378157ms","start":"2026-04-17T11:12:26.490107Z","end":"2026-04-17T11:12:26.635485Z","steps":["trace[1231436468] 'process raft request' (duration: 91.558955ms)","trace[1231436468] 'compare' (duration: 53.404149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:12:26.6357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.293295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/horizon\" ","response":"range_response_count:1 size:517"} {"level":"info","ts":"2026-04-17T11:12:26.635808Z","caller":"traceutil/trace.go:171","msg":"trace[1472860414] range","detail":"{range_begin:/registry/serviceaccounts/openstack/horizon; range_end:; response_count:1; response_revision:6249; }","duration":"113.458386ms","start":"2026-04-17T11:12:26.522324Z","end":"2026-04-17T11:12:26.635782Z","steps":["trace[1472860414] 'agreement among raft nodes before linearized reading' (duration: 113.170423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:12:26.636124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.790759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/horizon\" ","response":"range_response_count:1 size:517"} {"level":"info","ts":"2026-04-17T11:12:26.636191Z","caller":"traceutil/trace.go:171","msg":"trace[1816305156] range","detail":"{range_begin:/registry/serviceaccounts/openstack/horizon; range_end:; response_count:1; response_revision:6249; }","duration":"113.89434ms","start":"2026-04-17T11:12:26.522284Z","end":"2026-04-17T11:12:26.636178Z","steps":["trace[1816305156] 'agreement among raft nodes before linearized reading' (duration: 113.722249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:12:26.854023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.756733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T11:12:26.854183Z","caller":"traceutil/trace.go:171","msg":"trace[1346507207] linearizableReadLoop","detail":"{readStateIndex:6486; appliedIndex:6485; }","duration":"165.659925ms","start":"2026-04-17T11:12:26.688481Z","end":"2026-04-17T11:12:26.85414Z","steps":["trace[1346507207] 'read index received' (duration: 62.59053ms)","trace[1346507207] 'applied index is now lower than readState.Index' (duration: 103.067445ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:12:26.85424Z","caller":"traceutil/trace.go:171","msg":"trace[591516695] transaction","detail":"{read_only:false; response_revision:6251; number_of_response:1; }","duration":"210.772662ms","start":"2026-04-17T11:12:26.643431Z","end":"2026-04-17T11:12:26.854204Z","steps":["trace[591516695] 'process raft request' (duration: 107.704856ms)","trace[591516695] 'compare' (duration: 102.42674ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:12:26.854474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.477987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-17T11:12:26.854514Z","caller":"traceutil/trace.go:171","msg":"trace[1144043661] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:6251; }","duration":"211.542768ms","start":"2026-04-17T11:12:26.642959Z","end":"2026-04-17T11:12:26.854502Z","steps":["trace[1144043661] 'agreement among raft nodes before linearized reading' (duration: 211.299996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:12:26.854692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.661905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-5d5d98bd6c-lsz66\" ","response":"range_response_count:1 size:9236"} {"level":"info","ts":"2026-04-17T11:12:26.854749Z","caller":"traceutil/trace.go:171","msg":"trace[1405714147] range","detail":"{range_begin:/registry/pods/openstack/horizon-5d5d98bd6c-lsz66; range_end:; response_count:1; response_revision:6251; }","duration":"215.750215ms","start":"2026-04-17T11:12:26.638982Z","end":"2026-04-17T11:12:26.854732Z","steps":["trace[1405714147] 'agreement among raft nodes before linearized reading' (duration: 215.541273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:12:26.854978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.917777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-5d5d98bd6c-hpg52\" ","response":"range_response_count:1 size:9236"} {"level":"info","ts":"2026-04-17T11:12:26.85502Z","caller":"traceutil/trace.go:171","msg":"trace[102907584] range","detail":"{range_begin:/registry/pods/openstack/horizon-5d5d98bd6c-hpg52; range_end:; response_count:1; response_revision:6251; }","duration":"215.997559ms","start":"2026-04-17T11:12:26.63901Z","end":"2026-04-17T11:12:26.855008Z","steps":["trace[102907584] 'agreement among raft nodes before linearized reading' (duration: 215.905357ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:27.380005Z","caller":"traceutil/trace.go:171","msg":"trace[93415323] transaction","detail":"{read_only:false; response_revision:6255; number_of_response:1; }","duration":"179.972651ms","start":"2026-04-17T11:12:27.199996Z","end":"2026-04-17T11:12:27.379968Z","steps":["trace[93415323] 'process raft request' (duration: 98.97916ms)","trace[93415323] 'compare' (duration: 80.8135ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:12:27.380046Z","caller":"traceutil/trace.go:171","msg":"trace[990290172] transaction","detail":"{read_only:false; response_revision:6256; number_of_response:1; }","duration":"127.173397ms","start":"2026-04-17T11:12:27.252849Z","end":"2026-04-17T11:12:27.380023Z","steps":["trace[990290172] 'process raft request' (duration: 127.113406ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:27.380062Z","caller":"traceutil/trace.go:171","msg":"trace[1855799829] linearizableReadLoop","detail":"{readStateIndex:6490; appliedIndex:6489; }","duration":"179.791139ms","start":"2026-04-17T11:12:27.20024Z","end":"2026-04-17T11:12:27.380031Z","steps":["trace[1855799829] 'read index received' (duration: 98.748787ms)","trace[1855799829] 'applied index is now lower than readState.Index' (duration: 81.040252ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:12:27.380253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.991262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:12:27.380276Z","caller":"traceutil/trace.go:171","msg":"trace[218292519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6256; }","duration":"180.068702ms","start":"2026-04-17T11:12:27.2002Z","end":"2026-04-17T11:12:27.380269Z","steps":["trace[218292519] 'agreement among raft nodes before linearized reading' (duration: 179.945031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:12:40.115206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.156055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T11:12:40.115415Z","caller":"traceutil/trace.go:171","msg":"trace[1293777424] linearizableReadLoop","detail":"{readStateIndex:6609; appliedIndex:6607; }","duration":"118.52087ms","start":"2026-04-17T11:12:39.996877Z","end":"2026-04-17T11:12:40.115397Z","steps":["trace[1293777424] 'read index received' (duration: 80.936571ms)","trace[1293777424] 'applied index is now lower than readState.Index' (duration: 37.583179ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:12:40.115503Z","caller":"traceutil/trace.go:171","msg":"trace[1570136363] transaction","detail":"{read_only:false; response_revision:6372; number_of_response:1; }","duration":"231.726605ms","start":"2026-04-17T11:12:39.883767Z","end":"2026-04-17T11:12:40.115493Z","steps":["trace[1570136363] 'process raft request' (duration: 231.545514ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:40.115596Z","caller":"traceutil/trace.go:171","msg":"trace[1845691160] transaction","detail":"{read_only:false; response_revision:6371; number_of_response:1; }","duration":"265.628784ms","start":"2026-04-17T11:12:39.849935Z","end":"2026-04-17T11:12:40.115564Z","steps":["trace[1845691160] 'process raft request' (duration: 46.022905ms)","trace[1845691160] 'compare' (duration: 218.857663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:12:40.115754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.796963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:12:40.115888Z","caller":"traceutil/trace.go:171","msg":"trace[288171584] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:6372; }","duration":"119.021765ms","start":"2026-04-17T11:12:39.996835Z","end":"2026-04-17T11:12:40.115857Z","steps":["trace[288171584] 'agreement among raft nodes before linearized reading' (duration: 118.768183ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:40.389013Z","caller":"traceutil/trace.go:171","msg":"trace[1424333958] linearizableReadLoop","detail":"{readStateIndex:6611; appliedIndex:6610; }","duration":"252.281556ms","start":"2026-04-17T11:12:40.13671Z","end":"2026-04-17T11:12:40.388991Z","steps":["trace[1424333958] 'read index received' (duration: 76.704493ms)","trace[1424333958] 'applied index is now lower than readState.Index' (duration: 175.576313ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:12:40.389229Z","caller":"traceutil/trace.go:171","msg":"trace[1247198081] transaction","detail":"{read_only:false; response_revision:6374; number_of_response:1; }","duration":"262.050882ms","start":"2026-04-17T11:12:40.127162Z","end":"2026-04-17T11:12:40.389213Z","steps":["trace[1247198081] 'process raft request' (duration: 261.665279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:12:40.389461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.943761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:12:40.389571Z","caller":"traceutil/trace.go:171","msg":"trace[2085404096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6374; }","duration":"189.065712ms","start":"2026-04-17T11:12:40.200454Z","end":"2026-04-17T11:12:40.38952Z","steps":["trace[2085404096] 'agreement among raft nodes before linearized reading' (duration: 188.956001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:12:40.389822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.071423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-17T11:12:40.389955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.150792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-17T11:12:40.39Z","caller":"traceutil/trace.go:171","msg":"trace[128631574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6374; }","duration":"156.231453ms","start":"2026-04-17T11:12:40.233758Z","end":"2026-04-17T11:12:40.389989Z","steps":["trace[128631574] 'agreement among raft nodes before linearized reading' (duration: 156.128982ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:40.389911Z","caller":"traceutil/trace.go:171","msg":"trace[2115149194] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:6374; }","duration":"253.201034ms","start":"2026-04-17T11:12:40.136692Z","end":"2026-04-17T11:12:40.389893Z","steps":["trace[2115149194] 'agreement among raft nodes before linearized reading' (duration: 253.018682ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:48.270255Z","caller":"traceutil/trace.go:171","msg":"trace[739041358] transaction","detail":"{read_only:false; response_revision:6411; number_of_response:1; }","duration":"119.803193ms","start":"2026-04-17T11:12:48.150431Z","end":"2026-04-17T11:12:48.270234Z","steps":["trace[739041358] 'process raft request' (duration: 119.581541ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:48.656989Z","caller":"traceutil/trace.go:171","msg":"trace[1087225144] transaction","detail":"{read_only:false; response_revision:6417; number_of_response:1; }","duration":"214.609045ms","start":"2026-04-17T11:12:48.442305Z","end":"2026-04-17T11:12:48.656914Z","steps":["trace[1087225144] 'process raft request' (duration: 200.157387ms)","trace[1087225144] 'compare' (duration: 14.110025ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:12:48.657089Z","caller":"traceutil/trace.go:171","msg":"trace[1453553466] transaction","detail":"{read_only:false; response_revision:6418; number_of_response:1; }","duration":"213.244003ms","start":"2026-04-17T11:12:48.443823Z","end":"2026-04-17T11:12:48.657067Z","steps":["trace[1453553466] 'process raft request' (duration: 212.975031ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:12:48.656972Z","caller":"traceutil/trace.go:171","msg":"trace[1143326458] linearizableReadLoop","detail":"{readStateIndex:6657; appliedIndex:6655; }","duration":"192.239379ms","start":"2026-04-17T11:12:48.464689Z","end":"2026-04-17T11:12:48.656929Z","steps":["trace[1143326458] 'read index received' (duration: 177.720581ms)","trace[1143326458] 'applied index is now lower than readState.Index' (duration: 14.517798ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:12:48.657323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.608522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:12:48.657445Z","caller":"traceutil/trace.go:171","msg":"trace[2001292201] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6418; }","duration":"192.772653ms","start":"2026-04-17T11:12:48.464652Z","end":"2026-04-17T11:12:48.657425Z","steps":["trace[2001292201] 'agreement among raft nodes before linearized reading' (duration: 192.377829ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:13:01.031636Z","caller":"traceutil/trace.go:171","msg":"trace[1247436087] transaction","detail":"{read_only:false; response_revision:6537; number_of_response:1; }","duration":"155.622847ms","start":"2026-04-17T11:13:00.875998Z","end":"2026-04-17T11:13:01.03162Z","steps":["trace[1247436087] 'process raft request' (duration: 155.522116ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:13:01.058842Z","caller":"traceutil/trace.go:171","msg":"trace[568516517] transaction","detail":"{read_only:false; response_revision:6538; number_of_response:1; }","duration":"144.148676ms","start":"2026-04-17T11:13:00.914487Z","end":"2026-04-17T11:13:01.058636Z","steps":["trace[568516517] 'process raft request' (duration: 143.939104ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:13:01.178575Z","caller":"traceutil/trace.go:171","msg":"trace[2089551046] transaction","detail":"{read_only:false; response_revision:6540; number_of_response:1; }","duration":"108.06493ms","start":"2026-04-17T11:13:01.070488Z","end":"2026-04-17T11:13:01.178553Z","steps":["trace[2089551046] 'process raft request' (duration: 107.919048ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:13:09.96214Z","caller":"traceutil/trace.go:171","msg":"trace[2120005013] transaction","detail":"{read_only:false; response_revision:6587; number_of_response:1; }","duration":"165.613596ms","start":"2026-04-17T11:13:09.796497Z","end":"2026-04-17T11:13:09.962111Z","steps":["trace[2120005013] 'process raft request' (duration: 165.484285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:13:20.968854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.93294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T11:13:20.968969Z","caller":"traceutil/trace.go:171","msg":"trace[999307216] linearizableReadLoop","detail":"{readStateIndex:6890; appliedIndex:6889; }","duration":"241.42138ms","start":"2026-04-17T11:13:20.727533Z","end":"2026-04-17T11:13:20.968954Z","steps":["trace[999307216] 'read index received' (duration: 68.17µs)","trace[999307216] 'applied index is now lower than readState.Index' (duration: 241.35218ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:13:20.969148Z","caller":"traceutil/trace.go:171","msg":"trace[1675478797] transaction","detail":"{read_only:false; response_revision:6641; number_of_response:1; }","duration":"298.772085ms","start":"2026-04-17T11:13:20.670362Z","end":"2026-04-17T11:13:20.969134Z","steps":["trace[1675478797] 'process raft request' (duration: 35.486842ms)","trace[1675478797] 'compare' (duration: 262.638237ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T11:13:20.969334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.800403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-17T11:13:20.969373Z","caller":"traceutil/trace.go:171","msg":"trace[703432262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6641; }","duration":"241.889315ms","start":"2026-04-17T11:13:20.727475Z","end":"2026-04-17T11:13:20.969364Z","steps":["trace[703432262] 'agreement among raft nodes before linearized reading' (duration: 241.824174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:13:20.982404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.324508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-17T11:13:20.982463Z","caller":"traceutil/trace.go:171","msg":"trace[72323190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6642; }","duration":"251.426249ms","start":"2026-04-17T11:13:20.731023Z","end":"2026-04-17T11:13:20.98245Z","steps":["trace[72323190] 'agreement among raft nodes before linearized reading' (duration: 251.265137ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:13:20.982672Z","caller":"traceutil/trace.go:171","msg":"trace[1004815344] transaction","detail":"{read_only:false; response_revision:6642; number_of_response:1; }","duration":"103.55904ms","start":"2026-04-17T11:13:20.879103Z","end":"2026-04-17T11:13:20.982662Z","steps":["trace[1004815344] 'process raft request' (duration: 103.048684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:13:20.982868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.104691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-17T11:13:20.982906Z","caller":"traceutil/trace.go:171","msg":"trace[602269048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6642; }","duration":"123.165052ms","start":"2026-04-17T11:13:20.85973Z","end":"2026-04-17T11:13:20.982895Z","steps":["trace[602269048] 'agreement among raft nodes before linearized reading' (duration: 123.074841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:13:20.983069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.765713ms","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-04-17T11:13:20.983104Z","caller":"traceutil/trace.go:171","msg":"trace[779555570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6642; }","duration":"127.824593ms","start":"2026-04-17T11:13:20.855271Z","end":"2026-04-17T11:13:20.983095Z","steps":["trace[779555570] 'agreement among raft nodes before linearized reading' (duration: 127.746403ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:13:21.275376Z","caller":"traceutil/trace.go:171","msg":"trace[1597778321] linearizableReadLoop","detail":"{readStateIndex:6895; appliedIndex:6892; }","duration":"113.7988ms","start":"2026-04-17T11:13:21.161523Z","end":"2026-04-17T11:13:21.275322Z","steps":["trace[1597778321] 'read index received' (duration: 91.595645ms)","trace[1597778321] 'applied index is now lower than readState.Index' (duration: 22.202265ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:13:21.275606Z","caller":"traceutil/trace.go:171","msg":"trace[939893132] transaction","detail":"{read_only:false; response_revision:6644; number_of_response:1; }","duration":"287.911629ms","start":"2026-04-17T11:13:20.987685Z","end":"2026-04-17T11:13:21.275596Z","steps":["trace[939893132] 'process raft request' (duration: 265.403191ms)","trace[939893132] 'compare' (duration: 18.762786ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T11:13:21.275761Z","caller":"traceutil/trace.go:171","msg":"trace[1386142298] transaction","detail":"{read_only:false; response_revision:6645; number_of_response:1; }","duration":"287.691446ms","start":"2026-04-17T11:13:20.988063Z","end":"2026-04-17T11:13:21.275755Z","steps":["trace[1386142298] 'process raft request' (duration: 287.148892ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:13:21.275873Z","caller":"traceutil/trace.go:171","msg":"trace[1543130025] transaction","detail":"{read_only:false; response_revision:6646; number_of_response:1; }","duration":"287.670796ms","start":"2026-04-17T11:13:20.988193Z","end":"2026-04-17T11:13:21.275864Z","steps":["trace[1543130025] 'process raft request' (duration: 287.095831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:13:21.27907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.478659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:13:21.27912Z","caller":"traceutil/trace.go:171","msg":"trace[1090856144] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:6647; }","duration":"102.54842ms","start":"2026-04-17T11:13:21.176561Z","end":"2026-04-17T11:13:21.279109Z","steps":["trace[1090856144] 'agreement among raft nodes before linearized reading' (duration: 102.423319ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T11:13:21.279534Z","caller":"traceutil/trace.go:171","msg":"trace[199401578] transaction","detail":"{read_only:false; response_revision:6647; number_of_response:1; }","duration":"107.125732ms","start":"2026-04-17T11:13:21.172397Z","end":"2026-04-17T11:13:21.279523Z","steps":["trace[199401578] 'process raft request' (duration: 106.537936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T11:13:21.277501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.154992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T11:13:21.280032Z","caller":"traceutil/trace.go:171","msg":"trace[1258245869] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:6646; }","duration":"118.37829ms","start":"2026-04-17T11:13:21.161487Z","end":"2026-04-17T11:13:21.279865Z","steps":["trace[1258245869] 'agreement among raft nodes before linearized reading' (duration: 114.882749ms)"],"step_count":1}