{"level":"warn","ts":"2026-04-01T23:27:32.797724Z","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-01T23:27:32.797847Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-01T23:27:32.797931Z","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-01T23:27:32.797954Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-01T23:27:32.798208Z","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-01T23:27:32.799225Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-04-01T23:27:32.799395Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-01T23:27:32.803287Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.60942ms"} {"level":"info","ts":"2026-04-01T23:27:32.811907Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-04-01T23:27:32.811999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T23:27:32.812027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-04-01T23:27:32.812043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T23:27:32.812055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-04-01T23:27:32.812099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-04-01T23:27:32.818886Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T23:27:32.820321Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T23:27:32.821392Z","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-01T23:27:32.824218Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T23:27:32.824471Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T23:27:32.824613Z","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-01T23:27:32.824698Z","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-01T23:27:32.82471Z","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-01T23:27:32.826571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-04-01T23:27:32.826835Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-04-01T23:27:32.830952Z","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-01T23:27:32.831197Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T23:27:32.831228Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T23:27:32.831318Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-01T23:27:32.831329Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-04-01T23:27:33.413287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T23:27:33.413361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T23:27:33.413399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-04-01T23:27:33.413431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-04-01T23:27:33.413453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-01T23:27:33.413471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-04-01T23:27:33.413491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-04-01T23:27:33.414797Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T23:27:33.414985Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T23:27:33.415164Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T23:27:33.415726Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:27:33.416692Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:27:33.416818Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:27:33.416865Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:27:33.41781Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T23:27:33.41909Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-04-01T23:27:33.419262Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T23:27:33.419454Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T23:27:48.326431Z","caller":"traceutil/trace.go:171","msg":"trace[1717819733] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"100.624015ms","start":"2026-04-01T23:27:48.225785Z","end":"2026-04-01T23:27:48.326409Z","steps":["trace[1717819733] 'process raft request' (duration: 100.477422ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:48.332064Z","caller":"traceutil/trace.go:171","msg":"trace[411443656] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"100.432701ms","start":"2026-04-01T23:27:48.231602Z","end":"2026-04-01T23:27:48.332034Z","steps":["trace[411443656] 'process raft request' (duration: 100.307818ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:49.892288Z","caller":"traceutil/trace.go:171","msg":"trace[1538139868] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"104.452502ms","start":"2026-04-01T23:27:49.787814Z","end":"2026-04-01T23:27:49.892266Z","steps":["trace[1538139868] 'process raft request' (duration: 48.718048ms)","trace[1538139868] 'compare' (duration: 55.317533ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:27:49.892297Z","caller":"traceutil/trace.go:171","msg":"trace[1566018348] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"104.35698ms","start":"2026-04-01T23:27:49.787917Z","end":"2026-04-01T23:27:49.892274Z","steps":["trace[1566018348] 'process raft request' (duration: 104.131464ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:27:49.892463Z","caller":"traceutil/trace.go:171","msg":"trace[1494924058] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"101.508375ms","start":"2026-04-01T23:27:49.790837Z","end":"2026-04-01T23:27:49.892346Z","steps":["trace[1494924058] 'process raft request' (duration: 101.29323ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:01.654172Z","caller":"traceutil/trace.go:171","msg":"trace[148780165] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"461.882925ms","start":"2026-04-01T23:28:01.192265Z","end":"2026-04-01T23:28:01.654148Z","steps":["trace[148780165] 'read index received' (duration: 461.665791ms)","trace[148780165] 'applied index is now lower than readState.Index' (duration: 216.184µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:01.654245Z","caller":"traceutil/trace.go:171","msg":"trace[1498874351] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"627.795637ms","start":"2026-04-01T23:28:01.026422Z","end":"2026-04-01T23:28:01.654217Z","steps":["trace[1498874351] 'process raft request' (duration: 627.593743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:01.654321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.031359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:01.654414Z","caller":"traceutil/trace.go:171","msg":"trace[1281838134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:447; }","duration":"462.172002ms","start":"2026-04-01T23:28:01.192229Z","end":"2026-04-01T23:28:01.6544Z","steps":["trace[1281838134] 'agreement among raft nodes before linearized reading' (duration: 462.042629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:01.654456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:01.192212Z","time spent":"462.236413ms","remote":"127.0.0.1:56010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:28:01.654702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:01.02641Z","time spent":"627.896979ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:01.754789Z","caller":"traceutil/trace.go:171","msg":"trace[1869446821] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"100.513352ms","start":"2026-04-01T23:28:01.654246Z","end":"2026-04-01T23:28:01.754759Z","steps":["trace[1869446821] 'read index received' (duration: 58.584557ms)","trace[1869446821] 'applied index is now lower than readState.Index' (duration: 41.927255ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:01.754948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.244296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-01T23:28:01.755007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.714766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-04-01T23:28:01.755048Z","caller":"traceutil/trace.go:171","msg":"trace[625469680] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:447; }","duration":"558.786377ms","start":"2026-04-01T23:28:01.196248Z","end":"2026-04-01T23:28:01.755034Z","steps":["trace[625469680] 'agreement among raft nodes before linearized reading' (duration: 558.664105ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:01.755055Z","caller":"traceutil/trace.go:171","msg":"trace[154507463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:447; }","duration":"191.40258ms","start":"2026-04-01T23:28:01.563628Z","end":"2026-04-01T23:28:01.75503Z","steps":["trace[154507463] 'agreement among raft nodes before linearized reading' (duration: 191.190005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:01.75508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:01.196242Z","time spent":"558.829718ms","remote":"127.0.0.1:56106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12690,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-04-01T23:28:04.999233Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.027099585s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T23:28:04.999556Z","caller":"traceutil/trace.go:171","msg":"trace[381699773] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:500; }","duration":"293.338395ms","start":"2026-04-01T23:28:04.706194Z","end":"2026-04-01T23:28:04.999533Z","steps":["trace[381699773] 'read index received' (duration: 293.175081ms)","trace[381699773] 'applied index is now lower than readState.Index' (duration: 162.464µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:04.999725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.497388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:04.999743Z","caller":"traceutil/trace.go:171","msg":"trace[829037196] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.027685158s","start":"2026-04-01T23:28:03.972019Z","end":"2026-04-01T23:28:04.999704Z","steps":["trace[829037196] 'process raft request' (duration: 1.027395642s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:04.999819Z","caller":"traceutil/trace.go:171","msg":"trace[1280790128] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:487; }","duration":"293.64475ms","start":"2026-04-01T23:28:04.706155Z","end":"2026-04-01T23:28:04.999799Z","steps":["trace[1280790128] 'agreement among raft nodes before linearized reading' (duration: 293.473307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:04.999922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:03.971992Z","time spent":"1.027831321s","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:05.52944Z","caller":"traceutil/trace.go:171","msg":"trace[603689584] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"263.927599ms","start":"2026-04-01T23:28:05.265468Z","end":"2026-04-01T23:28:05.529395Z","steps":["trace[603689584] 'process raft request' (duration: 263.745735ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.529562Z","caller":"traceutil/trace.go:171","msg":"trace[1188982391] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"263.990992ms","start":"2026-04-01T23:28:05.26553Z","end":"2026-04-01T23:28:05.529521Z","steps":["trace[1188982391] 'process raft request' (duration: 263.808517ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.529591Z","caller":"traceutil/trace.go:171","msg":"trace[1856241976] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"259.658224ms","start":"2026-04-01T23:28:05.269903Z","end":"2026-04-01T23:28:05.529561Z","steps":["trace[1856241976] 'process raft request' (duration: 259.545721ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.530384Z","caller":"traceutil/trace.go:171","msg":"trace[612278085] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"255.530041ms","start":"2026-04-01T23:28:05.274832Z","end":"2026-04-01T23:28:05.530362Z","steps":["trace[612278085] 'process raft request' (duration: 254.690323ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.530409Z","caller":"traceutil/trace.go:171","msg":"trace[223868789] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"232.349895ms","start":"2026-04-01T23:28:05.29803Z","end":"2026-04-01T23:28:05.53038Z","steps":["trace[223868789] 'process raft request' (duration: 231.600618ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.530437Z","caller":"traceutil/trace.go:171","msg":"trace[210165652] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"251.962802ms","start":"2026-04-01T23:28:05.278414Z","end":"2026-04-01T23:28:05.530377Z","steps":["trace[210165652] 'process raft request' (duration: 251.128574ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.530677Z","caller":"traceutil/trace.go:171","msg":"trace[1320624726] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"260.671456ms","start":"2026-04-01T23:28:05.269971Z","end":"2026-04-01T23:28:05.530643Z","steps":["trace[1320624726] 'process raft request' (duration: 259.527701ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.530831Z","caller":"traceutil/trace.go:171","msg":"trace[402480004] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"240.769502ms","start":"2026-04-01T23:28:05.29005Z","end":"2026-04-01T23:28:05.530819Z","steps":["trace[402480004] 'process raft request' (duration: 239.518505ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.53087Z","caller":"traceutil/trace.go:171","msg":"trace[908345810] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"262.270442ms","start":"2026-04-01T23:28:05.268468Z","end":"2026-04-01T23:28:05.530739Z","steps":["trace[908345810] 'process raft request' (duration: 260.905861ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.542281Z","caller":"traceutil/trace.go:171","msg":"trace[559564663] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"232.968498ms","start":"2026-04-01T23:28:05.309301Z","end":"2026-04-01T23:28:05.54227Z","steps":["trace[559564663] 'process raft request' (duration: 232.60717ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.542726Z","caller":"traceutil/trace.go:171","msg":"trace[1200747780] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"233.227144ms","start":"2026-04-01T23:28:05.309491Z","end":"2026-04-01T23:28:05.542718Z","steps":["trace[1200747780] 'process raft request' (duration: 232.502988ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.543124Z","caller":"traceutil/trace.go:171","msg":"trace[1317589860] transaction","detail":"{read_only:false; number_of_response:1; response_revision:500; }","duration":"233.175073ms","start":"2026-04-01T23:28:05.309943Z","end":"2026-04-01T23:28:05.543118Z","steps":["trace[1317589860] 'process raft request' (duration: 232.294034ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.543284Z","caller":"traceutil/trace.go:171","msg":"trace[1830081648] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"214.200049ms","start":"2026-04-01T23:28:05.329077Z","end":"2026-04-01T23:28:05.543277Z","steps":["trace[1830081648] 'process raft request' (duration: 213.854082ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:06.032919Z","caller":"traceutil/trace.go:171","msg":"trace[704724904] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"108.724687ms","start":"2026-04-01T23:28:05.924164Z","end":"2026-04-01T23:28:06.032889Z","steps":["trace[704724904] 'process raft request' (duration: 108.398209ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:07.095999Z","caller":"traceutil/trace.go:171","msg":"trace[273309645] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"130.030042ms","start":"2026-04-01T23:28:06.965943Z","end":"2026-04-01T23:28:07.095973Z","steps":["trace[273309645] 'read index received' (duration: 129.49002ms)","trace[273309645] 'applied index is now lower than readState.Index' (duration: 539.322µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:07.096114Z","caller":"traceutil/trace.go:171","msg":"trace[2024724287] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"142.9843ms","start":"2026-04-01T23:28:06.953121Z","end":"2026-04-01T23:28:07.096106Z","steps":["trace[2024724287] 'process raft request' (duration: 142.489709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:07.096371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.238716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:07.096588Z","caller":"traceutil/trace.go:171","msg":"trace[1826213278] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:568; }","duration":"130.504603ms","start":"2026-04-01T23:28:06.966063Z","end":"2026-04-01T23:28:07.096568Z","steps":["trace[1826213278] 'agreement among raft nodes before linearized reading' (duration: 130.201766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:07.096677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.716227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-04-01T23:28:07.096711Z","caller":"traceutil/trace.go:171","msg":"trace[2014860629] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:568; }","duration":"130.784509ms","start":"2026-04-01T23:28:06.965915Z","end":"2026-04-01T23:28:07.096699Z","steps":["trace[2014860629] 'agreement among raft nodes before linearized reading' (duration: 130.678226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:07.672859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.704482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:07.672951Z","caller":"traceutil/trace.go:171","msg":"trace[1913555239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"262.846674ms","start":"2026-04-01T23:28:07.410085Z","end":"2026-04-01T23:28:07.672932Z","steps":["trace[1913555239] 'range keys from in-memory index tree' (duration: 262.623389ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:07.680899Z","caller":"traceutil/trace.go:171","msg":"trace[916546219] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"151.858089ms","start":"2026-04-01T23:28:07.529025Z","end":"2026-04-01T23:28:07.680883Z","steps":["trace[916546219] 'process raft request' (duration: 151.756896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:08.677384Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879987507974349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:28:08.772876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.644297ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:08.773016Z","caller":"traceutil/trace.go:171","msg":"trace[1391311982] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:597; }","duration":"784.772289ms","start":"2026-04-01T23:28:07.988203Z","end":"2026-04-01T23:28:08.772975Z","steps":["trace[1391311982] 'range keys from in-memory index tree' (duration: 784.388921ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:08.793033Z","caller":"traceutil/trace.go:171","msg":"trace[73706772] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"663.201516ms","start":"2026-04-01T23:28:08.129814Z","end":"2026-04-01T23:28:08.793016Z","steps":["trace[73706772] 'process raft request' (duration: 663.101764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:08.793127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:08.12979Z","time spent":"663.292669ms","remote":"127.0.0.1:56054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:28:08.857015Z","caller":"traceutil/trace.go:171","msg":"trace[1307976151] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:616; }","duration":"679.906868ms","start":"2026-04-01T23:28:08.177059Z","end":"2026-04-01T23:28:08.856966Z","steps":["trace[1307976151] 'read index received' (duration: 615.984663ms)","trace[1307976151] 'applied index is now lower than readState.Index' (duration: 63.921445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:08.857219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.093183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:08.857317Z","caller":"traceutil/trace.go:171","msg":"trace[1223849010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"680.360739ms","start":"2026-04-01T23:28:08.176945Z","end":"2026-04-01T23:28:08.857306Z","steps":["trace[1223849010] 'agreement among raft nodes before linearized reading' (duration: 680.152954ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:08.857329Z","caller":"traceutil/trace.go:171","msg":"trace[202934948] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"305.349552ms","start":"2026-04-01T23:28:08.551955Z","end":"2026-04-01T23:28:08.857305Z","steps":["trace[202934948] 'process raft request' (duration: 304.828721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:08.857385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:08.176927Z","time spent":"680.413569ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:28:08.857462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:08.551937Z","time spent":"305.454424ms","remote":"127.0.0.1:56054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:28:08.857547Z","caller":"traceutil/trace.go:171","msg":"trace[2058922675] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"527.370666ms","start":"2026-04-01T23:28:08.329599Z","end":"2026-04-01T23:28:08.856969Z","steps":["trace[2058922675] 'process raft request' (duration: 527.042599ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:08.857532Z","caller":"traceutil/trace.go:171","msg":"trace[816010616] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"125.216973ms","start":"2026-04-01T23:28:08.73222Z","end":"2026-04-01T23:28:08.857437Z","steps":["trace[816010616] 'process raft request' (duration: 124.707202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:08.857692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:08.329579Z","time spent":"528.047792ms","remote":"127.0.0.1:56054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:28:08.857699Z","caller":"traceutil/trace.go:171","msg":"trace[1650968788] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"216.216025ms","start":"2026-04-01T23:28:08.641249Z","end":"2026-04-01T23:28:08.857465Z","steps":["trace[1650968788] 'process raft request' (duration: 215.59698ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:15.476641Z","caller":"traceutil/trace.go:171","msg":"trace[1363788192] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"329.400818ms","start":"2026-04-01T23:28:15.147215Z","end":"2026-04-01T23:28:15.476616Z","steps":["trace[1363788192] 'read index received' (duration: 329.120492ms)","trace[1363788192] 'applied index is now lower than readState.Index' (duration: 279.156µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:15.476781Z","caller":"traceutil/trace.go:171","msg":"trace[1800146927] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"408.72771ms","start":"2026-04-01T23:28:15.068038Z","end":"2026-04-01T23:28:15.476765Z","steps":["trace[1800146927] 'process raft request' (duration: 408.413843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:15.476879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:15.068018Z","time spent":"408.79057ms","remote":"127.0.0.1:56194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:15.477134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.928771ms","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-01T23:28:15.477166Z","caller":"traceutil/trace.go:171","msg":"trace[675145126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:660; }","duration":"329.997383ms","start":"2026-04-01T23:28:15.147159Z","end":"2026-04-01T23:28:15.477156Z","steps":["trace[675145126] 'agreement among raft nodes before linearized reading' (duration: 329.88762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:15.477193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:15.147131Z","time spent":"330.055894ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T23:28:15.477184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.572468ms","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-01T23:28:15.477258Z","caller":"traceutil/trace.go:171","msg":"trace[1182228994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"322.66971ms","start":"2026-04-01T23:28:15.15457Z","end":"2026-04-01T23:28:15.47724Z","steps":["trace[1182228994] 'agreement among raft nodes before linearized reading' (duration: 322.399353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:15.477237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.485246ms","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":"warn","ts":"2026-04-01T23:28:15.477203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.478044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:15.477314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:15.154565Z","time spent":"322.736251ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-01T23:28:15.477316Z","caller":"traceutil/trace.go:171","msg":"trace[279791589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:660; }","duration":"292.609769ms","start":"2026-04-01T23:28:15.184689Z","end":"2026-04-01T23:28:15.477299Z","steps":["trace[279791589] 'agreement among raft nodes before linearized reading' (duration: 292.343272ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:15.477339Z","caller":"traceutil/trace.go:171","msg":"trace[1846711416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"296.650449ms","start":"2026-04-01T23:28:15.180674Z","end":"2026-04-01T23:28:15.477324Z","steps":["trace[1846711416] 'agreement among raft nodes before linearized reading' (duration: 296.419563ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:15.622008Z","caller":"traceutil/trace.go:171","msg":"trace[178712245] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"140.497795ms","start":"2026-04-01T23:28:15.481484Z","end":"2026-04-01T23:28:15.621982Z","steps":["trace[178712245] 'process raft request' (duration: 90.164142ms)","trace[178712245] 'compare' (duration: 50.2115ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:15.630319Z","caller":"traceutil/trace.go:171","msg":"trace[536848792] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"147.90971ms","start":"2026-04-01T23:28:15.482383Z","end":"2026-04-01T23:28:15.630293Z","steps":["trace[536848792] 'process raft request' (duration: 147.682665ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:15.630442Z","caller":"traceutil/trace.go:171","msg":"trace[203773699] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"147.410868ms","start":"2026-04-01T23:28:15.48301Z","end":"2026-04-01T23:28:15.630421Z","steps":["trace[203773699] 'process raft request' (duration: 147.180243ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:16.188668Z","caller":"traceutil/trace.go:171","msg":"trace[486767136] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"249.869506ms","start":"2026-04-01T23:28:15.93878Z","end":"2026-04-01T23:28:16.18865Z","steps":["trace[486767136] 'read index received' (duration: 249.718392ms)","trace[486767136] 'applied index is now lower than readState.Index' (duration: 150.494µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:16.188956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.169843ms","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-01T23:28:16.189121Z","caller":"traceutil/trace.go:171","msg":"trace[1046806002] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"311.406488ms","start":"2026-04-01T23:28:15.877705Z","end":"2026-04-01T23:28:16.189112Z","steps":["trace[1046806002] 'process raft request' (duration: 310.845215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:16.189262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:15.877688Z","time spent":"311.4654ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:16.189074Z","caller":"traceutil/trace.go:171","msg":"trace[1042299515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:665; }","duration":"250.345767ms","start":"2026-04-01T23:28:15.938709Z","end":"2026-04-01T23:28:16.189054Z","steps":["trace[1042299515] 'agreement among raft nodes before linearized reading' (duration: 250.05182ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:16.332628Z","caller":"traceutil/trace.go:171","msg":"trace[1193002546] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"138.792877ms","start":"2026-04-01T23:28:16.193805Z","end":"2026-04-01T23:28:16.332598Z","steps":["trace[1193002546] 'process raft request' (duration: 55.315034ms)","trace[1193002546] 'compare' (duration: 83.368451ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:17.593112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159734025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-01T23:28:17.593264Z","caller":"traceutil/trace.go:171","msg":"trace[1455138868] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:667; }","duration":"1.159906958s","start":"2026-04-01T23:28:16.433338Z","end":"2026-04-01T23:28:17.593245Z","steps":["trace[1455138868] 'range keys from in-memory index tree' (duration: 1.159663993s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:17.593621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:16.433329Z","time spent":"1.160271987s","remote":"127.0.0.1:56044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-04-01T23:28:17.59319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.550303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:17.593875Z","caller":"traceutil/trace.go:171","msg":"trace[2095471939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"413.224019ms","start":"2026-04-01T23:28:17.180581Z","end":"2026-04-01T23:28:17.593805Z","steps":["trace[2095471939] 'range keys from in-memory index tree' (duration: 412.481252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:17.593946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:17.180563Z","time spent":"413.366951ms","remote":"127.0.0.1:56010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:28:18.134082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879987507974598,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:28:18.248647Z","caller":"traceutil/trace.go:171","msg":"trace[1928786743] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"614.788567ms","start":"2026-04-01T23:28:17.633836Z","end":"2026-04-01T23:28:18.248624Z","steps":["trace[1928786743] 'read index received' (duration: 614.313646ms)","trace[1928786743] 'applied index is now lower than readState.Index' (duration: 473.791µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:18.248793Z","caller":"traceutil/trace.go:171","msg":"trace[171959658] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"621.150969ms","start":"2026-04-01T23:28:17.62761Z","end":"2026-04-01T23:28:18.248761Z","steps":["trace[171959658] 'process raft request' (duration: 620.644038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:18.24895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:17.627601Z","time spent":"621.265571ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:18.249014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.139815ms","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-01T23:28:18.249048Z","caller":"traceutil/trace.go:171","msg":"trace[870856490] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:668; }","duration":"615.254208ms","start":"2026-04-01T23:28:17.633784Z","end":"2026-04-01T23:28:18.249039Z","steps":["trace[870856490] 'agreement among raft nodes before linearized reading' (duration: 614.953271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:18.249074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:17.633758Z","time spent":"615.309988ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T23:28:18.24903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.488619ms","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-01T23:28:18.249406Z","caller":"traceutil/trace.go:171","msg":"trace[838148339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:668; }","duration":"614.935759ms","start":"2026-04-01T23:28:17.634446Z","end":"2026-04-01T23:28:18.249382Z","steps":["trace[838148339] 'agreement among raft nodes before linearized reading' (duration: 614.341476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:18.249459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:17.634435Z","time spent":"615.010891ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-01T23:28:32.506726Z","caller":"traceutil/trace.go:171","msg":"trace[1310164902] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"118.161287ms","start":"2026-04-01T23:28:32.388541Z","end":"2026-04-01T23:28:32.506702Z","steps":["trace[1310164902] 'read index received' (duration: 117.965342ms)","trace[1310164902] 'applied index is now lower than readState.Index' (duration: 194.915µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:32.506994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.352559ms","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-01T23:28:32.506999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.444733ms","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-01T23:28:32.507035Z","caller":"traceutil/trace.go:171","msg":"trace[1732913903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"104.434701ms","start":"2026-04-01T23:28:32.40259Z","end":"2026-04-01T23:28:32.507025Z","steps":["trace[1732913903] 'agreement among raft nodes before linearized reading' (duration: 104.292428ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:32.507053Z","caller":"traceutil/trace.go:171","msg":"trace[1012162391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"118.556015ms","start":"2026-04-01T23:28:32.388484Z","end":"2026-04-01T23:28:32.50704Z","steps":["trace[1012162391] 'agreement among raft nodes before linearized reading' (duration: 118.382232ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:32.506835Z","caller":"traceutil/trace.go:171","msg":"trace[712992901] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"122.426311ms","start":"2026-04-01T23:28:32.384386Z","end":"2026-04-01T23:28:32.506812Z","steps":["trace[712992901] 'process raft request' (duration: 122.090283ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:36.563251Z","caller":"traceutil/trace.go:171","msg":"trace[638849367] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"509.488208ms","start":"2026-04-01T23:28:36.053738Z","end":"2026-04-01T23:28:36.563226Z","steps":["trace[638849367] 'process raft request' (duration: 509.074128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:36.563591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:36.05371Z","time spent":"509.738983ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:36.564016Z","caller":"traceutil/trace.go:171","msg":"trace[1837891424] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:810; }","duration":"229.839093ms","start":"2026-04-01T23:28:36.334162Z","end":"2026-04-01T23:28:36.564001Z","steps":["trace[1837891424] 'read index received' (duration: 228.676427ms)","trace[1837891424] 'applied index is now lower than readState.Index' (duration: 1.162026ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:36.56422Z","caller":"traceutil/trace.go:171","msg":"trace[1010013444] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"376.282668ms","start":"2026-04-01T23:28:36.187926Z","end":"2026-04-01T23:28:36.564209Z","steps":["trace[1010013444] 'process raft request' (duration: 376.007172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:36.564324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:36.187895Z","time spent":"376.36202ms","remote":"127.0.0.1:60074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:36.564729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.764435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:36.564806Z","caller":"traceutil/trace.go:171","msg":"trace[1159571869] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:788; }","duration":"126.834277ms","start":"2026-04-01T23:28:36.437936Z","end":"2026-04-01T23:28:36.56477Z","steps":["trace[1159571869] 'agreement among raft nodes before linearized reading' (duration: 126.757076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:36.564571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.60881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:36.564887Z","caller":"traceutil/trace.go:171","msg":"trace[1763392008] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:788; }","duration":"151.982078ms","start":"2026-04-01T23:28:36.412885Z","end":"2026-04-01T23:28:36.564867Z","steps":["trace[1763392008] 'agreement among raft nodes before linearized reading' (duration: 151.58415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:36.566308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.796805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-01T23:28:36.566429Z","caller":"traceutil/trace.go:171","msg":"trace[519238621] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:788; }","duration":"232.281728ms","start":"2026-04-01T23:28:36.334109Z","end":"2026-04-01T23:28:36.566391Z","steps":["trace[519238621] 'agreement among raft nodes before linearized reading' (duration: 230.539779ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:10.8755Z","caller":"traceutil/trace.go:171","msg":"trace[1430872259] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1038; }","duration":"114.944123ms","start":"2026-04-01T23:29:10.760537Z","end":"2026-04-01T23:29:10.875481Z","steps":["trace[1430872259] 'read index received' (duration: 4.545242ms)","trace[1430872259] 'applied index is now lower than readState.Index' (duration: 110.398001ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:10.875561Z","caller":"traceutil/trace.go:171","msg":"trace[2124519810] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"115.2816ms","start":"2026-04-01T23:29:10.760247Z","end":"2026-04-01T23:29:10.875529Z","steps":["trace[2124519810] 'process raft request' (duration: 115.051725ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:10.875669Z","caller":"traceutil/trace.go:171","msg":"trace[1614453871] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"111.114018ms","start":"2026-04-01T23:29:10.764538Z","end":"2026-04-01T23:29:10.875652Z","steps":["trace[1614453871] 'process raft request' (duration: 110.919014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:10.875704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.146427ms","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-01T23:29:10.875712Z","caller":"traceutil/trace.go:171","msg":"trace[1872118467] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"111.827783ms","start":"2026-04-01T23:29:10.763807Z","end":"2026-04-01T23:29:10.875634Z","steps":["trace[1872118467] 'process raft request' (duration: 111.623179ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:10.875774Z","caller":"traceutil/trace.go:171","msg":"trace[2007219246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"115.249439ms","start":"2026-04-01T23:29:10.760517Z","end":"2026-04-01T23:29:10.875766Z","steps":["trace[2007219246] 'agreement among raft nodes before linearized reading' (duration: 115.048025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:11.942142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.058221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:29:11.942245Z","caller":"traceutil/trace.go:171","msg":"trace[49364512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"759.204505ms","start":"2026-04-01T23:29:11.183011Z","end":"2026-04-01T23:29:11.942216Z","steps":["trace[49364512] 'range keys from in-memory index tree' (duration: 758.921269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:11.942295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:11.18299Z","time spent":"759.295357ms","remote":"127.0.0.1:56010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:29:11.942405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.137725ms","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-01T23:29:11.942483Z","caller":"traceutil/trace.go:171","msg":"trace[1431152400] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1009; }","duration":"707.263037ms","start":"2026-04-01T23:29:11.235203Z","end":"2026-04-01T23:29:11.942466Z","steps":["trace[1431152400] 'count revisions from in-memory index tree' (duration: 707.073853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:11.942527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:11.235187Z","time spent":"707.328439ms","remote":"127.0.0.1:60160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":26,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:29:12.842713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18150961s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-04-01T23:29:12.843689Z","caller":"traceutil/trace.go:171","msg":"trace[342930336] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1043; }","duration":"900.043176ms","start":"2026-04-01T23:29:11.943615Z","end":"2026-04-01T23:29:12.843658Z","steps":["trace[342930336] 'read index received' (duration: 42.381µs)","trace[342930336] 'applied index is now lower than readState.Index' (duration: 899.998765ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:12.843826Z","caller":"traceutil/trace.go:171","msg":"trace[1256709037] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"1.188624998s","start":"2026-04-01T23:29:11.655188Z","end":"2026-04-01T23:29:12.843813Z","steps":["trace[1256709037] 'process raft request' (duration: 1.187635125s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:12.843942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:11.655161Z","time spent":"1.18869707s","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:29:12.846055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.412908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:29:12.846145Z","caller":"traceutil/trace.go:171","msg":"trace[1189139266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"902.54321ms","start":"2026-04-01T23:29:11.94358Z","end":"2026-04-01T23:29:12.846124Z","steps":["trace[1189139266] 'agreement among raft nodes before linearized reading' (duration: 900.413703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:12.846196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:11.943565Z","time spent":"902.617922ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T23:29:35.306182Z","caller":"traceutil/trace.go:171","msg":"trace[724371363] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"352.751144ms","start":"2026-04-01T23:29:34.953409Z","end":"2026-04-01T23:29:35.30616Z","steps":["trace[724371363] 'read index received' (duration: 352.538419ms)","trace[724371363] 'applied index is now lower than readState.Index' (duration: 211.885µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:35.30628Z","caller":"traceutil/trace.go:171","msg":"trace[2049042473] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"373.879365ms","start":"2026-04-01T23:29:34.932364Z","end":"2026-04-01T23:29:35.306243Z","steps":["trace[2049042473] 'process raft request' (duration: 373.65057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:35.306471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:34.932338Z","time spent":"374.016728ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:29:35.306504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.082621ms","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-01T23:29:35.306543Z","caller":"traceutil/trace.go:171","msg":"trace[237602345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1082; }","duration":"353.185553ms","start":"2026-04-01T23:29:34.953347Z","end":"2026-04-01T23:29:35.306533Z","steps":["trace[237602345] 'agreement among raft nodes before linearized reading' (duration: 352.940978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:35.306614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:34.953324Z","time spent":"353.279476ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T23:29:35.306816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.137672ms","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":"warn","ts":"2026-04-01T23:29:35.306841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.051453ms","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-01T23:29:35.306853Z","caller":"traceutil/trace.go:171","msg":"trace[1634430287] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1082; }","duration":"279.187373ms","start":"2026-04-01T23:29:35.027656Z","end":"2026-04-01T23:29:35.306843Z","steps":["trace[1634430287] 'agreement among raft nodes before linearized reading' (duration: 279.088971ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:35.30687Z","caller":"traceutil/trace.go:171","msg":"trace[1028450963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"280.095224ms","start":"2026-04-01T23:29:35.026768Z","end":"2026-04-01T23:29:35.306864Z","steps":["trace[1028450963] 'agreement among raft nodes before linearized reading' (duration: 279.996452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:35.306855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.857666ms","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-01T23:29:35.307032Z","caller":"traceutil/trace.go:171","msg":"trace[259668058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1082; }","duration":"279.0455ms","start":"2026-04-01T23:29:35.027976Z","end":"2026-04-01T23:29:35.307022Z","steps":["trace[259668058] 'agreement among raft nodes before linearized reading' (duration: 278.813135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:35.307158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.294171ms","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-01T23:29:35.307439Z","caller":"traceutil/trace.go:171","msg":"trace[1274187391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1082; }","duration":"281.775131ms","start":"2026-04-01T23:29:35.025645Z","end":"2026-04-01T23:29:35.30742Z","steps":["trace[1274187391] 'agreement among raft nodes before linearized reading' (duration: 280.82441ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:35.435025Z","caller":"traceutil/trace.go:171","msg":"trace[784182989] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"124.051535ms","start":"2026-04-01T23:29:35.310946Z","end":"2026-04-01T23:29:35.434998Z","steps":["trace[784182989] 'process raft request' (duration: 123.889882ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:35.443164Z","caller":"traceutil/trace.go:171","msg":"trace[254222361] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"132.131685ms","start":"2026-04-01T23:29:35.311008Z","end":"2026-04-01T23:29:35.44314Z","steps":["trace[254222361] 'process raft request' (duration: 131.962561ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:35.443472Z","caller":"traceutil/trace.go:171","msg":"trace[545792179] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"131.504912ms","start":"2026-04-01T23:29:35.311942Z","end":"2026-04-01T23:29:35.443447Z","steps":["trace[545792179] 'process raft request' (duration: 131.146314ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:35.443496Z","caller":"traceutil/trace.go:171","msg":"trace[1753852873] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"130.55486ms","start":"2026-04-01T23:29:35.312923Z","end":"2026-04-01T23:29:35.443478Z","steps":["trace[1753852873] 'process raft request' (duration: 130.431608ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:35.443507Z","caller":"traceutil/trace.go:171","msg":"trace[2139558897] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"130.462608ms","start":"2026-04-01T23:29:35.313015Z","end":"2026-04-01T23:29:35.443478Z","steps":["trace[2139558897] 'process raft request' (duration: 130.424487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:37.154029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.491406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T23:29:37.154107Z","caller":"traceutil/trace.go:171","msg":"trace[1205619729] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1088; }","duration":"311.624698ms","start":"2026-04-01T23:29:36.842465Z","end":"2026-04-01T23:29:37.15409Z","steps":["trace[1205619729] 'count revisions from in-memory index tree' (duration: 311.418023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:37.154142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:36.842447Z","time spent":"311.68732ms","remote":"127.0.0.1:60228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":28,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-04-01T23:29:37.547448Z","caller":"traceutil/trace.go:171","msg":"trace[498839431] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"100.466235ms","start":"2026-04-01T23:29:37.446945Z","end":"2026-04-01T23:29:37.547411Z","steps":["trace[498839431] 'read index received' (duration: 60.673154ms)","trace[498839431] 'applied index is now lower than readState.Index' (duration: 39.792321ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:37.547463Z","caller":"traceutil/trace.go:171","msg":"trace[127617710] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"101.530629ms","start":"2026-04-01T23:29:37.445909Z","end":"2026-04-01T23:29:37.547439Z","steps":["trace[127617710] 'process raft request' (duration: 61.737897ms)","trace[127617710] 'compare' (duration: 39.493625ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:29:37.548165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.064918ms","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-01T23:29:37.548231Z","caller":"traceutil/trace.go:171","msg":"trace[224849534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1091; }","duration":"101.156891ms","start":"2026-04-01T23:29:37.447056Z","end":"2026-04-01T23:29:37.548213Z","steps":["trace[224849534] 'agreement among raft nodes before linearized reading' (duration: 100.950116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:37.548394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.441466ms","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-01T23:29:37.548441Z","caller":"traceutil/trace.go:171","msg":"trace[1695085075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1091; }","duration":"101.488717ms","start":"2026-04-01T23:29:37.44694Z","end":"2026-04-01T23:29:37.548428Z","steps":["trace[1695085075] 'agreement among raft nodes before linearized reading' (duration: 100.572767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:37.548474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.16382ms","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-01T23:29:37.548604Z","caller":"traceutil/trace.go:171","msg":"trace[2037488689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1091; }","duration":"101.243272ms","start":"2026-04-01T23:29:37.447277Z","end":"2026-04-01T23:29:37.54852Z","steps":["trace[2037488689] 'agreement among raft nodes before linearized reading' (duration: 101.080709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:37.548474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.096289ms","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-01T23:29:37.548671Z","caller":"traceutil/trace.go:171","msg":"trace[709372153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1091; }","duration":"101.318194ms","start":"2026-04-01T23:29:37.447333Z","end":"2026-04-01T23:29:37.548651Z","steps":["trace[709372153] 'agreement among raft nodes before linearized reading' (duration: 101.013297ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:54.04311Z","caller":"traceutil/trace.go:171","msg":"trace[487196407] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"383.41204ms","start":"2026-04-01T23:29:53.659673Z","end":"2026-04-01T23:29:54.043085Z","steps":["trace[487196407] 'read index received' (duration: 383.128194ms)","trace[487196407] 'applied index is now lower than readState.Index' (duration: 282.846µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:54.043143Z","caller":"traceutil/trace.go:171","msg":"trace[919814491] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"602.436011ms","start":"2026-04-01T23:29:53.440683Z","end":"2026-04-01T23:29:54.043119Z","steps":["trace[919814491] 'process raft request' (duration: 602.223596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:54.043291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:53.440663Z","time spent":"602.558433ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:29:54.04335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.659635ms","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-01T23:29:54.043393Z","caller":"traceutil/trace.go:171","msg":"trace[369318053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1193; }","duration":"383.754869ms","start":"2026-04-01T23:29:53.659626Z","end":"2026-04-01T23:29:54.043381Z","steps":["trace[369318053] 'agreement among raft nodes before linearized reading' (duration: 383.601124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:54.043436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:53.659606Z","time spent":"383.82075ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T23:29:54.04348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.677658ms","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-01T23:29:54.043505Z","caller":"traceutil/trace.go:171","msg":"trace[1182469972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1193; }","duration":"336.736168ms","start":"2026-04-01T23:29:53.706762Z","end":"2026-04-01T23:29:54.043498Z","steps":["trace[1182469972] 'agreement among raft nodes before linearized reading' (duration: 336.632876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:54.043526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:53.706744Z","time spent":"336.77648ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T23:29:54.043609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.348919ms","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-01T23:29:54.043629Z","caller":"traceutil/trace.go:171","msg":"trace[1118621062] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1193; }","duration":"266.413631ms","start":"2026-04-01T23:29:53.777211Z","end":"2026-04-01T23:29:54.043624Z","steps":["trace[1118621062] 'agreement among raft nodes before linearized reading' (duration: 266.35698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:54.043746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.070798ms","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-01T23:29:54.043796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.572759ms","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-01T23:29:54.043851Z","caller":"traceutil/trace.go:171","msg":"trace[752631513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1193; }","duration":"329.17999ms","start":"2026-04-01T23:29:53.714646Z","end":"2026-04-01T23:29:54.043826Z","steps":["trace[752631513] 'agreement among raft nodes before linearized reading' (duration: 328.940745ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:54.043852Z","caller":"traceutil/trace.go:171","msg":"trace[1685609102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1193; }","duration":"329.648291ms","start":"2026-04-01T23:29:53.714189Z","end":"2026-04-01T23:29:54.043837Z","steps":["trace[1685609102] 'agreement among raft nodes before linearized reading' (duration: 329.506528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:54.043914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:53.714182Z","time spent":"329.721723ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T23:29:54.043913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:53.714615Z","time spent":"329.284483ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T23:29:54.681418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879987507975735,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:29:55.011869Z","caller":"traceutil/trace.go:171","msg":"trace[1712229269] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"963.477766ms","start":"2026-04-01T23:29:54.048311Z","end":"2026-04-01T23:29:55.011788Z","steps":["trace[1712229269] 'process raft request' (duration: 957.703958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:55.012056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:54.048285Z","time spent":"963.671491ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:29:55.015364Z","caller":"traceutil/trace.go:171","msg":"trace[1489014675] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"966.772069ms","start":"2026-04-01T23:29:54.048581Z","end":"2026-04-01T23:29:55.015354Z","steps":["trace[1489014675] 'process raft request' (duration: 966.526504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:55.015424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:54.048571Z","time spent":"966.82826ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:29:55.015589Z","caller":"traceutil/trace.go:171","msg":"trace[810169013] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"966.903443ms","start":"2026-04-01T23:29:54.048679Z","end":"2026-04-01T23:29:55.015583Z","steps":["trace[810169013] 'process raft request' (duration: 966.527004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:55.015621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:54.048655Z","time spent":"966.950285ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:29:55.016025Z","caller":"traceutil/trace.go:171","msg":"trace[485009561] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"967.20276ms","start":"2026-04-01T23:29:54.048783Z","end":"2026-04-01T23:29:55.015985Z","steps":["trace[485009561] 'process raft request' (duration: 966.661708ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:55.01606Z","caller":"traceutil/trace.go:171","msg":"trace[417849091] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"967.270371ms","start":"2026-04-01T23:29:54.048783Z","end":"2026-04-01T23:29:55.016053Z","steps":["trace[417849091] 'process raft request' (duration: 967.078287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:55.016189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:54.048772Z","time spent":"967.331042ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:29:55.016282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:54.048768Z","time spent":"967.453595ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:29:55.017128Z","caller":"traceutil/trace.go:171","msg":"trace[1067895966] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1238; }","duration":"836.494864ms","start":"2026-04-01T23:29:54.18061Z","end":"2026-04-01T23:29:55.017105Z","steps":["trace[1067895966] 'read index received' (duration: 825.408968ms)","trace[1067895966] 'applied index is now lower than readState.Index' (duration: 11.082926ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:29:55.017243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.622898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:29:55.017293Z","caller":"traceutil/trace.go:171","msg":"trace[1942079084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1198; }","duration":"836.707059ms","start":"2026-04-01T23:29:54.180573Z","end":"2026-04-01T23:29:55.01728Z","steps":["trace[1942079084] 'agreement among raft nodes before linearized reading' (duration: 836.607557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:55.017326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:29:54.180559Z","time spent":"836.760641ms","remote":"127.0.0.1:56010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:30:09.477946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.148985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:30:09.4781Z","caller":"traceutil/trace.go:171","msg":"trace[1732558229] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"372.962119ms","start":"2026-04-01T23:30:09.105123Z","end":"2026-04-01T23:30:09.478085Z","steps":["trace[1732558229] 'process raft request' (duration: 372.915428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:09.478188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:09.105105Z","time spent":"373.054021ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:30:09.478485Z","caller":"traceutil/trace.go:171","msg":"trace[1564201527] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"404.130249ms","start":"2026-04-01T23:30:09.074342Z","end":"2026-04-01T23:30:09.478473Z","steps":["trace[1564201527] 'process raft request' (duration: 220.375071ms)","trace[1564201527] 'compare' (duration: 183.043233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:30:09.478547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:09.07433Z","time spent":"404.189921ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:30:09.478681Z","caller":"traceutil/trace.go:171","msg":"trace[2076991058] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"378.227266ms","start":"2026-04-01T23:30:09.100445Z","end":"2026-04-01T23:30:09.478672Z","steps":["trace[2076991058] 'read index received' (duration: 194.558799ms)","trace[2076991058] 'applied index is now lower than readState.Index' (duration: 183.667567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:30:09.478924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.134809ms","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-01T23:30:09.478961Z","caller":"traceutil/trace.go:171","msg":"trace[1387160477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1257; }","duration":"399.180771ms","start":"2026-04-01T23:30:09.079772Z","end":"2026-04-01T23:30:09.478952Z","steps":["trace[1387160477] 'agreement among raft nodes before linearized reading' (duration: 399.070398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:09.478988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:09.079769Z","time spent":"399.213792ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T23:30:09.479155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.389635ms","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-01T23:30:09.479194Z","caller":"traceutil/trace.go:171","msg":"trace[290149656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"399.436216ms","start":"2026-04-01T23:30:09.079747Z","end":"2026-04-01T23:30:09.479183Z","steps":["trace[290149656] 'agreement among raft nodes before linearized reading' (duration: 399.354204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:09.479218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:09.079741Z","time spent":"399.470947ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T23:30:09.479378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.687067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-04-01T23:30:09.479409Z","caller":"traceutil/trace.go:171","msg":"trace[1385906569] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1257; }","duration":"327.742628ms","start":"2026-04-01T23:30:09.151659Z","end":"2026-04-01T23:30:09.479402Z","steps":["trace[1385906569] 'agreement among raft nodes before linearized reading' (duration: 327.667466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:09.479432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:09.151644Z","time spent":"327.783019ms","remote":"127.0.0.1:56186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5352,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-01T23:30:10.549691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.938272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:30:10.549836Z","caller":"traceutil/trace.go:171","msg":"trace[2054782985] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"958.67605ms","start":"2026-04-01T23:30:09.591071Z","end":"2026-04-01T23:30:10.549747Z","steps":["trace[2054782985] 'process raft request' (duration: 602.541254ms)","trace[2054782985] 'compare' (duration: 355.620595ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:30:10.55Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:09.591057Z","time spent":"958.799153ms","remote":"127.0.0.1:60228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:30:11.131771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.770682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:30:11.131916Z","caller":"traceutil/trace.go:171","msg":"trace[205774435] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"1.468016779s","start":"2026-04-01T23:30:09.663882Z","end":"2026-04-01T23:30:11.131899Z","steps":["trace[205774435] 'process raft request' (duration: 1.022055182s)","trace[205774435] 'compare' (duration: 445.354533ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:30:11.131955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:09.663864Z","time spent":"1.46807554s","remote":"127.0.0.1:56182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:30:11.132278Z","caller":"traceutil/trace.go:171","msg":"trace[1740660319] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"1.46804292s","start":"2026-04-01T23:30:09.664226Z","end":"2026-04-01T23:30:11.132269Z","steps":["trace[1740660319] 'process raft request' (duration: 1.467612471s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:11.132318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:09.664201Z","time spent":"1.468098371s","remote":"127.0.0.1:60112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:30:11.132512Z","caller":"traceutil/trace.go:171","msg":"trace[94604136] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"1.464742396s","start":"2026-04-01T23:30:09.667764Z","end":"2026-04-01T23:30:11.132506Z","steps":["trace[94604136] 'process raft request' (duration: 1.464330667s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:11.13254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:09.667756Z","time spent":"1.464767588s","remote":"127.0.0.1:60306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:30:11.132645Z","caller":"traceutil/trace.go:171","msg":"trace[789327934] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1309; }","duration":"952.924852ms","start":"2026-04-01T23:30:10.179699Z","end":"2026-04-01T23:30:11.132624Z","steps":["trace[789327934] 'read index received' (duration: 13.852626ms)","trace[789327934] 'applied index is now lower than readState.Index' (duration: 939.070866ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:11.132741Z","caller":"traceutil/trace.go:171","msg":"trace[639905708] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"1.013413282s","start":"2026-04-01T23:30:10.119314Z","end":"2026-04-01T23:30:11.132727Z","steps":["trace[639905708] 'process raft request' (duration: 1.013032504s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:11.132748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.055164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:11.132767Z","caller":"traceutil/trace.go:171","msg":"trace[1063662760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"953.104666ms","start":"2026-04-01T23:30:10.179657Z","end":"2026-04-01T23:30:11.132762Z","steps":["trace[1063662760] 'agreement among raft nodes before linearized reading' (duration: 953.072735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:11.132782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:10.179637Z","time spent":"953.141027ms","remote":"127.0.0.1:56010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:30:11.132838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:10.119296Z","time spent":"1.013469373s","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:30:11.185608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.400404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:11.18564Z","caller":"traceutil/trace.go:171","msg":"trace[1615468788] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"648.198764ms","start":"2026-04-01T23:30:10.537407Z","end":"2026-04-01T23:30:11.185606Z","steps":["trace[1615468788] 'process raft request' (duration: 647.859717ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:11.185699Z","caller":"traceutil/trace.go:171","msg":"trace[1052431057] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1267; }","duration":"585.532737ms","start":"2026-04-01T23:30:10.600138Z","end":"2026-04-01T23:30:11.185671Z","steps":["trace[1052431057] 'agreement among raft nodes before linearized reading' (duration: 585.389774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:11.185783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:10.600115Z","time spent":"585.648009ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":26,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:30:11.185802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:10.537384Z","time spent":"648.330568ms","remote":"127.0.0.1:56186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5640,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:30:12.426489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879987507975898,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:30:12.875204Z","caller":"traceutil/trace.go:171","msg":"trace[233240872] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1323; }","duration":"948.957385ms","start":"2026-04-01T23:30:11.926213Z","end":"2026-04-01T23:30:12.875171Z","steps":["trace[233240872] 'read index received' (duration: 948.944745ms)","trace[233240872] 'applied index is now lower than readState.Index' (duration: 10.72µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:12.875233Z","caller":"traceutil/trace.go:171","msg":"trace[492851581] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"949.195ms","start":"2026-04-01T23:30:11.925978Z","end":"2026-04-01T23:30:12.875173Z","steps":["trace[492851581] 'process raft request' (duration: 948.776201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:12.875507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.730605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:12.875559Z","caller":"traceutil/trace.go:171","msg":"trace[497917354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1274; }","duration":"694.837127ms","start":"2026-04-01T23:30:12.180706Z","end":"2026-04-01T23:30:12.875544Z","steps":["trace[497917354] 'agreement among raft nodes before linearized reading' (duration: 694.659874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:12.875601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:12.180683Z","time spent":"694.907648ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:30:12.875589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:11.925962Z","time spent":"949.373504ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:30:12.875881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.65316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:12.875916Z","caller":"traceutil/trace.go:171","msg":"trace[984173648] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1274; }","duration":"949.694941ms","start":"2026-04-01T23:30:11.926209Z","end":"2026-04-01T23:30:12.875904Z","steps":["trace[984173648] 'agreement among raft nodes before linearized reading' (duration: 949.63757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:12.875946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:11.926188Z","time spent":"949.750182ms","remote":"127.0.0.1:33358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:30:13.639268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879987507975903,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:30:13.866911Z","caller":"traceutil/trace.go:171","msg":"trace[1451645564] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"728.104194ms","start":"2026-04-01T23:30:13.138783Z","end":"2026-04-01T23:30:13.866887Z","steps":["trace[1451645564] 'read index received' (duration: 676.597233ms)","trace[1451645564] 'applied index is now lower than readState.Index' (duration: 51.505631ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:13.866991Z","caller":"traceutil/trace.go:171","msg":"trace[1183350377] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"728.812449ms","start":"2026-04-01T23:30:13.138139Z","end":"2026-04-01T23:30:13.866951Z","steps":["trace[1183350377] 'process raft request' (duration: 677.258217ms)","trace[1183350377] 'compare' (duration: 50.942759ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:30:13.867062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.909326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:13.86709Z","caller":"traceutil/trace.go:171","msg":"trace[1850767777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"688.981867ms","start":"2026-04-01T23:30:13.1781Z","end":"2026-04-01T23:30:13.867082Z","steps":["trace[1850767777] 'agreement among raft nodes before linearized reading' (duration: 688.913316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:13.867083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.265907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-01T23:30:13.86712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:13.178083Z","time spent":"689.024339ms","remote":"127.0.0.1:56010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T23:30:13.867146Z","caller":"traceutil/trace.go:171","msg":"trace[942008668] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1275; }","duration":"728.37572ms","start":"2026-04-01T23:30:13.138753Z","end":"2026-04-01T23:30:13.867129Z","steps":["trace[942008668] 'agreement among raft nodes before linearized reading' (duration: 728.232417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:13.867143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:13.138111Z","time spent":"728.952253ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:30:13.867197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:13.138744Z","time spent":"728.438741ms","remote":"127.0.0.1:33270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:30:13.86727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.029122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-01T23:30:13.867339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.159816ms","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-01T23:30:13.86735Z","caller":"traceutil/trace.go:171","msg":"trace[841813505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1275; }","duration":"187.177316ms","start":"2026-04-01T23:30:13.680154Z","end":"2026-04-01T23:30:13.867332Z","steps":["trace[841813505] 'agreement among raft nodes before linearized reading' (duration: 186.958951ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:13.867364Z","caller":"traceutil/trace.go:171","msg":"trace[1488169208] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1275; }","duration":"257.233567ms","start":"2026-04-01T23:30:13.610124Z","end":"2026-04-01T23:30:13.867357Z","steps":["trace[1488169208] 'agreement among raft nodes before linearized reading' (duration: 257.134515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:13.867461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.780463ms","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-01T23:30:13.86748Z","caller":"traceutil/trace.go:171","msg":"trace[995150735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"306.846985ms","start":"2026-04-01T23:30:13.560627Z","end":"2026-04-01T23:30:13.867474Z","steps":["trace[995150735] 'agreement among raft nodes before linearized reading' (duration: 306.793503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:13.867496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:13.560585Z","time spent":"306.907316ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T23:30:13.867557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.296216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-01T23:30:13.867582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.866256ms","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-01T23:30:13.8676Z","caller":"traceutil/trace.go:171","msg":"trace[1253642946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"651.926077ms","start":"2026-04-01T23:30:13.215668Z","end":"2026-04-01T23:30:13.867594Z","steps":["trace[1253642946] 'agreement among raft nodes before linearized reading' (duration: 651.870376ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:13.867602Z","caller":"traceutil/trace.go:171","msg":"trace[1201068246] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1275; }","duration":"126.386238ms","start":"2026-04-01T23:30:13.741199Z","end":"2026-04-01T23:30:13.867586Z","steps":["trace[1201068246] 'agreement among raft nodes before linearized reading' (duration: 126.273916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:13.867617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:13.215635Z","time spent":"651.976349ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T23:30:13.867712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.812266ms","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-01T23:30:13.867728Z","caller":"traceutil/trace.go:171","msg":"trace[1999336262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1275; }","duration":"176.829076ms","start":"2026-04-01T23:30:13.690893Z","end":"2026-04-01T23:30:13.867722Z","steps":["trace[1999336262] 'agreement among raft nodes before linearized reading' (duration: 176.776245ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:16.009335Z","caller":"traceutil/trace.go:171","msg":"trace[438512472] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"121.048672ms","start":"2026-04-01T23:30:15.888271Z","end":"2026-04-01T23:30:16.009319Z","steps":["trace[438512472] 'read index received' (duration: 120.937089ms)","trace[438512472] 'applied index is now lower than readState.Index' (duration: 110.873µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:16.009396Z","caller":"traceutil/trace.go:171","msg":"trace[1054366385] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"131.633925ms","start":"2026-04-01T23:30:15.87773Z","end":"2026-04-01T23:30:16.009364Z","steps":["trace[1054366385] 'process raft request' (duration: 131.491182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:16.009786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.472091ms","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-01T23:30:16.009843Z","caller":"traceutil/trace.go:171","msg":"trace[569915427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1287; }","duration":"121.598973ms","start":"2026-04-01T23:30:15.888232Z","end":"2026-04-01T23:30:16.009831Z","steps":["trace[569915427] 'agreement among raft nodes before linearized reading' (duration: 121.176244ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:16.141696Z","caller":"traceutil/trace.go:171","msg":"trace[1304056348] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"128.561557ms","start":"2026-04-01T23:30:16.013096Z","end":"2026-04-01T23:30:16.141657Z","steps":["trace[1304056348] 'process raft request' (duration: 116.732646ms)","trace[1304056348] 'compare' (duration: 11.688698ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:16.143832Z","caller":"traceutil/trace.go:171","msg":"trace[285520464] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"130.429089ms","start":"2026-04-01T23:30:16.013381Z","end":"2026-04-01T23:30:16.14381Z","steps":["trace[285520464] 'process raft request' (duration: 130.275676ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:16.144092Z","caller":"traceutil/trace.go:171","msg":"trace[1712260407] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"130.622253ms","start":"2026-04-01T23:30:16.013457Z","end":"2026-04-01T23:30:16.144079Z","steps":["trace[1712260407] 'process raft request' (duration: 130.301876ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:16.144152Z","caller":"traceutil/trace.go:171","msg":"trace[840294472] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"128.978496ms","start":"2026-04-01T23:30:16.015153Z","end":"2026-04-01T23:30:16.144131Z","steps":["trace[840294472] 'process raft request' (duration: 128.835493ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:16.144248Z","caller":"traceutil/trace.go:171","msg":"trace[1635091499] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"128.67737ms","start":"2026-04-01T23:30:16.015559Z","end":"2026-04-01T23:30:16.144236Z","steps":["trace[1635091499] 'process raft request' (duration: 128.523796ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:19.199512Z","caller":"traceutil/trace.go:171","msg":"trace[1299582346] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"122.122844ms","start":"2026-04-01T23:30:19.077366Z","end":"2026-04-01T23:30:19.199489Z","steps":["trace[1299582346] 'process raft request' (duration: 121.996731ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:23.069789Z","caller":"traceutil/trace.go:171","msg":"trace[66457508] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"113.519855ms","start":"2026-04-01T23:30:22.956243Z","end":"2026-04-01T23:30:23.069763Z","steps":["trace[66457508] 'process raft request' (duration: 113.378961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:24.196715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017644195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:24.196766Z","caller":"traceutil/trace.go:171","msg":"trace[712219978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"1.017739317s","start":"2026-04-01T23:30:23.179014Z","end":"2026-04-01T23:30:24.196754Z","steps":["trace[712219978] 'range keys from in-memory index tree' (duration: 1.017562313s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:24.196753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.505515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-01T23:30:24.196837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.074796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-01T23:30:24.196833Z","caller":"traceutil/trace.go:171","msg":"trace[1641979050] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1349; }","duration":"193.619328ms","start":"2026-04-01T23:30:24.003196Z","end":"2026-04-01T23:30:24.196815Z","steps":["trace[1641979050] 'range keys from in-memory index tree' (duration: 193.350291ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:24.196857Z","caller":"traceutil/trace.go:171","msg":"trace[1853058559] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1349; }","duration":"683.129618ms","start":"2026-04-01T23:30:23.513721Z","end":"2026-04-01T23:30:24.19685Z","steps":["trace[1853058559] 'range keys from in-memory index tree' (duration: 682.992804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:24.19687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:23.513699Z","time spent":"683.168209ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-01T23:30:24.196787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:23.178993Z","time spent":"1.017789548s","remote":"127.0.0.1:56010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:30:24.196373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.599169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:24.197906Z","caller":"traceutil/trace.go:171","msg":"trace[1852557751] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1349; }","duration":"630.185135ms","start":"2026-04-01T23:30:23.567694Z","end":"2026-04-01T23:30:24.19788Z","steps":["trace[1852557751] 'count revisions from in-memory index tree' (duration: 628.501488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:24.198531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:23.567671Z","time spent":"630.83697ms","remote":"127.0.0.1:33310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":26,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-04-01T23:30:24.199378Z","caller":"traceutil/trace.go:171","msg":"trace[2120247311] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"158.508179ms","start":"2026-04-01T23:30:24.04086Z","end":"2026-04-01T23:30:24.199369Z","steps":["trace[2120247311] 'process raft request' (duration: 158.359616ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:52.845159Z","caller":"traceutil/trace.go:171","msg":"trace[1140587129] transaction","detail":"{read_only:false; response_revision:1759; number_of_response:1; }","duration":"126.226273ms","start":"2026-04-01T23:31:52.718907Z","end":"2026-04-01T23:31:52.845133Z","steps":["trace[1140587129] 'process raft request' (duration: 81.132946ms)","trace[1140587129] 'compare' (duration: 44.906263ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:52.845462Z","caller":"traceutil/trace.go:171","msg":"trace[1203868098] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"125.797664ms","start":"2026-04-01T23:31:52.719653Z","end":"2026-04-01T23:31:52.845451Z","steps":["trace[1203868098] 'process raft request' (duration: 125.733223ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:52.845443Z","caller":"traceutil/trace.go:171","msg":"trace[1787711170] transaction","detail":"{read_only:false; response_revision:1762; number_of_response:1; }","duration":"126.422998ms","start":"2026-04-01T23:31:52.718995Z","end":"2026-04-01T23:31:52.845418Z","steps":["trace[1787711170] 'process raft request' (duration: 126.360436ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:52.845485Z","caller":"traceutil/trace.go:171","msg":"trace[1932322010] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"126.52354ms","start":"2026-04-01T23:31:52.718948Z","end":"2026-04-01T23:31:52.845472Z","steps":["trace[1932322010] 'process raft request' (duration: 126.327976ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:52.845527Z","caller":"traceutil/trace.go:171","msg":"trace[1863721290] transaction","detail":"{read_only:false; response_revision:1760; number_of_response:1; }","duration":"126.612692ms","start":"2026-04-01T23:31:52.718901Z","end":"2026-04-01T23:31:52.845513Z","steps":["trace[1863721290] 'process raft request' (duration: 126.193963ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:54.966371Z","caller":"traceutil/trace.go:171","msg":"trace[1606800638] transaction","detail":"{read_only:false; response_revision:1770; number_of_response:1; }","duration":"113.119624ms","start":"2026-04-01T23:31:54.853224Z","end":"2026-04-01T23:31:54.966344Z","steps":["trace[1606800638] 'process raft request' (duration: 103.497021ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:54.966401Z","caller":"traceutil/trace.go:171","msg":"trace[1783825680] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"113.086772ms","start":"2026-04-01T23:31:54.853281Z","end":"2026-04-01T23:31:54.966368Z","steps":["trace[1783825680] 'process raft request' (duration: 112.98571ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:54.966398Z","caller":"traceutil/trace.go:171","msg":"trace[2041971411] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"113.048341ms","start":"2026-04-01T23:31:54.853321Z","end":"2026-04-01T23:31:54.966369Z","steps":["trace[2041971411] 'process raft request' (duration: 112.98375ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:54.966504Z","caller":"traceutil/trace.go:171","msg":"trace[969459967] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"111.394476ms","start":"2026-04-01T23:31:54.855086Z","end":"2026-04-01T23:31:54.96648Z","steps":["trace[969459967] 'process raft request' (duration: 111.249773ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:57.085096Z","caller":"traceutil/trace.go:171","msg":"trace[1505143461] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"111.314453ms","start":"2026-04-01T23:31:56.973749Z","end":"2026-04-01T23:31:57.085064Z","steps":["trace[1505143461] 'process raft request' (duration: 73.341843ms)","trace[1505143461] 'compare' (duration: 37.761436ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:57.085106Z","caller":"traceutil/trace.go:171","msg":"trace[789532940] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"107.865228ms","start":"2026-04-01T23:31:56.977221Z","end":"2026-04-01T23:31:57.085086Z","steps":["trace[789532940] 'process raft request' (duration: 107.785396ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:57.085306Z","caller":"traceutil/trace.go:171","msg":"trace[1708187031] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"107.96993ms","start":"2026-04-01T23:31:56.977328Z","end":"2026-04-01T23:31:57.085298Z","steps":["trace[1708187031] 'process raft request' (duration: 107.719224ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:57.085332Z","caller":"traceutil/trace.go:171","msg":"trace[736441215] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"107.579332ms","start":"2026-04-01T23:31:56.97773Z","end":"2026-04-01T23:31:57.085309Z","steps":["trace[736441215] 'process raft request' (duration: 107.503391ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:33:27.675914Z","caller":"traceutil/trace.go:171","msg":"trace[2125638641] linearizableReadLoop","detail":"{readStateIndex:2306; appliedIndex:2305; }","duration":"120.252066ms","start":"2026-04-01T23:33:27.55564Z","end":"2026-04-01T23:33:27.675892Z","steps":["trace[2125638641] 'read index received' (duration: 120.090092ms)","trace[2125638641] 'applied index is now lower than readState.Index' (duration: 160.894µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:33:27.676233Z","caller":"traceutil/trace.go:171","msg":"trace[251297560] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"296.657503ms","start":"2026-04-01T23:33:27.379562Z","end":"2026-04-01T23:33:27.676219Z","steps":["trace[251297560] 'process raft request' (duration: 296.221193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:33:27.676482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.826169ms","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-01T23:33:27.67652Z","caller":"traceutil/trace.go:171","msg":"trace[2005436697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2217; }","duration":"120.913211ms","start":"2026-04-01T23:33:27.555597Z","end":"2026-04-01T23:33:27.676511Z","steps":["trace[2005436697] 'agreement among raft nodes before linearized reading' (duration: 120.794699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:33:27.676791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.843579ms","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-01T23:33:27.676828Z","caller":"traceutil/trace.go:171","msg":"trace[384607768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2217; }","duration":"120.902101ms","start":"2026-04-01T23:33:27.555917Z","end":"2026-04-01T23:33:27.676819Z","steps":["trace[384607768] 'agreement among raft nodes before linearized reading' (duration: 120.766228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:33:27.677212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.106427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-01T23:33:27.677249Z","caller":"traceutil/trace.go:171","msg":"trace[1168768608] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2217; }","duration":"117.186859ms","start":"2026-04-01T23:33:27.560054Z","end":"2026-04-01T23:33:27.677241Z","steps":["trace[1168768608] 'agreement among raft nodes before linearized reading' (duration: 117.107728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:33:27.677369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.257187ms","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-01T23:33:27.677397Z","caller":"traceutil/trace.go:171","msg":"trace[409148734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2217; }","duration":"120.323489ms","start":"2026-04-01T23:33:27.557067Z","end":"2026-04-01T23:33:27.67739Z","steps":["trace[409148734] 'agreement among raft nodes before linearized reading' (duration: 120.250247ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:33:35.640319Z","caller":"traceutil/trace.go:171","msg":"trace[165859312] linearizableReadLoop","detail":"{readStateIndex:2348; appliedIndex:2347; }","duration":"259.837481ms","start":"2026-04-01T23:33:35.380459Z","end":"2026-04-01T23:33:35.640296Z","steps":["trace[165859312] 'read index received' (duration: 259.79416ms)","trace[165859312] 'applied index is now lower than readState.Index' (duration: 42.381µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:33:35.640446Z","caller":"traceutil/trace.go:171","msg":"trace[748849678] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"419.729021ms","start":"2026-04-01T23:33:35.220698Z","end":"2026-04-01T23:33:35.640427Z","steps":["trace[748849678] 'process raft request' (duration: 419.307361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:33:35.640559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.080446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-01T23:33:35.64074Z","caller":"traceutil/trace.go:171","msg":"trace[1800235304] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2258; }","duration":"260.27062ms","start":"2026-04-01T23:33:35.380453Z","end":"2026-04-01T23:33:35.640724Z","steps":["trace[1800235304] 'agreement among raft nodes before linearized reading' (duration: 259.987824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:33:35.640571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:33:35.220678Z","time spent":"419.813873ms","remote":"127.0.0.1:60098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":37,"request content":"compare: success:> failure: >"}