{"level":"warn","ts":"2026-05-07T23:24:01.439541Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:24:01.439645Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.201:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.201:2380","--initial-cluster=instance=https://199.19.213.201:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.201:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.201:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-07T23:24:01.439723Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:24:01.439739Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-05-07T23:24:01.439756Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T23:24:01.440515Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"]} {"level":"info","ts":"2026-05-07T23:24:01.440665Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.201:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-07T23:24:01.44765Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.57988ms"} {"level":"info","ts":"2026-05-07T23:24:01.456161Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"61bc451777564e43","cluster-id":"b1814cb801e33ddc"} {"level":"info","ts":"2026-05-07T23:24:01.456732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T23:24:01.456797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 0"} {"level":"info","ts":"2026-05-07T23:24:01.458429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 61bc451777564e43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T23:24:01.458472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 1"} {"level":"info","ts":"2026-05-07T23:24:01.458594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"warn","ts":"2026-05-07T23:24:01.467709Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T23:24:01.469159Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T23:24:01.470485Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-07T23:24:01.472938Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"61bc451777564e43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T23:24:01.474747Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"61bc451777564e43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T23:24:01.474862Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T23:24:01.474892Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T23:24:01.474901Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T23:24:01.478818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"info","ts":"2026-05-07T23:24:01.479035Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","added-peer-id":"61bc451777564e43","added-peer-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-05-07T23:24:01.479875Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T23:24:01.480099Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"61bc451777564e43","initial-advertise-peer-urls":["https://199.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T23:24:01.480138Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T23:24:01.480766Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-05-07T23:24:01.480881Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-05-07T23:24:01.759284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T23:24:01.759411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T23:24:01.759475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgPreVoteResp from 61bc451777564e43 at term 1"} {"level":"info","ts":"2026-05-07T23:24:01.759521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became candidate at term 2"} {"level":"info","ts":"2026-05-07T23:24:01.759553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgVoteResp from 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-05-07T23:24:01.759595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became leader at term 2"} {"level":"info","ts":"2026-05-07T23:24:01.759633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 61bc451777564e43 elected leader 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-05-07T23:24:01.760693Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"61bc451777564e43","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.201:2379]}","request-path":"/0/members/61bc451777564e43/attributes","cluster-id":"b1814cb801e33ddc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T23:24:01.760783Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:24:01.765317Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:24:01.765676Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:24:01.766316Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T23:24:01.766406Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T23:24:01.766934Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.201:2379"} {"level":"info","ts":"2026-05-07T23:24:01.768747Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T23:24:01.769186Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:24:01.769306Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:24:01.769346Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:24:31.675039Z","caller":"traceutil/trace.go:171","msg":"trace[1509565313] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"121.829351ms","start":"2026-05-07T23:24:31.553173Z","end":"2026-05-07T23:24:31.675002Z","steps":["trace[1509565313] 'read index received' (duration: 121.620136ms)","trace[1509565313] 'applied index is now lower than readState.Index' (duration: 208.275µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:24:31.675307Z","caller":"traceutil/trace.go:171","msg":"trace[1309342372] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"250.189411ms","start":"2026-05-07T23:24:31.42511Z","end":"2026-05-07T23:24:31.675299Z","steps":["trace[1309342372] 'process raft request' (duration: 249.781922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:31.675474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.287202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T23:24:31.675517Z","caller":"traceutil/trace.go:171","msg":"trace[787563668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"122.381494ms","start":"2026-05-07T23:24:31.553126Z","end":"2026-05-07T23:24:31.675507Z","steps":["trace[787563668] 'agreement among raft nodes before linearized reading' (duration: 122.306213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:32.988867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639524901659096690,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T23:24:32.992932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.223446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:24:32.99305Z","caller":"traceutil/trace.go:171","msg":"trace[997457238] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"922.399981ms","start":"2026-05-07T23:24:32.070622Z","end":"2026-05-07T23:24:32.993022Z","steps":["trace[997457238] 'process raft request' (duration: 85.222441ms)","trace[997457238] 'compare' (duration: 835.94341ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:24:32.993136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:32.070612Z","time spent":"922.479724ms","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:24:33.2351Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.078910426s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T23:24:33.270583Z","caller":"traceutil/trace.go:171","msg":"trace[1881992085] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:542; }","duration":"782.03661ms","start":"2026-05-07T23:24:32.488527Z","end":"2026-05-07T23:24:33.270564Z","steps":["trace[1881992085] 'read index received' (duration: 746.696811ms)","trace[1881992085] 'applied index is now lower than readState.Index' (duration: 35.339089ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:24:33.270711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.164593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T23:24:33.270743Z","caller":"traceutil/trace.go:171","msg":"trace[1031841186] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"782.251665ms","start":"2026-05-07T23:24:32.488483Z","end":"2026-05-07T23:24:33.270735Z","steps":["trace[1031841186] 'agreement among raft nodes before linearized reading' (duration: 782.157043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:33.270768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:32.488463Z","time spent":"782.299016ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T23:24:33.270855Z","caller":"traceutil/trace.go:171","msg":"trace[1904677236] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.179052661s","start":"2026-05-07T23:24:32.091775Z","end":"2026-05-07T23:24:33.270828Z","steps":["trace[1904677236] 'process raft request' (duration: 1.178552129s)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:33.270938Z","caller":"traceutil/trace.go:171","msg":"trace[477119537] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.166445494s","start":"2026-05-07T23:24:32.104484Z","end":"2026-05-07T23:24:33.27093Z","steps":["trace[477119537] 'process raft request' (duration: 1.165943572s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:33.270989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:32.10447Z","time spent":"1.166485655s","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:24:33.270995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:32.091762Z","time spent":"1.179160363s","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:24:33.271133Z","caller":"traceutil/trace.go:171","msg":"trace[44143584] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.117255558s","start":"2026-05-07T23:24:32.153871Z","end":"2026-05-07T23:24:33.271127Z","steps":["trace[44143584] 'process raft request' (duration: 1.116638674s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:33.271176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:32.153845Z","time spent":"1.11731502s","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:24:33.271293Z","caller":"traceutil/trace.go:171","msg":"trace[394817790] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.186003209s","start":"2026-05-07T23:24:32.085272Z","end":"2026-05-07T23:24:33.271275Z","steps":["trace[394817790] 'process raft request' (duration: 1.150074285s)","trace[394817790] 'compare' (duration: 34.806847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:24:33.2714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:32.08525Z","time spent":"1.186098342s","remote":"127.0.0.1:45602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:24:38.586881Z","caller":"traceutil/trace.go:171","msg":"trace[274946942] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"224.488857ms","start":"2026-05-07T23:24:38.362367Z","end":"2026-05-07T23:24:38.586855Z","steps":["trace[274946942] 'process raft request' (duration: 218.680277ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:38.794643Z","caller":"traceutil/trace.go:171","msg":"trace[117368806] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"140.643189ms","start":"2026-05-07T23:24:38.653896Z","end":"2026-05-07T23:24:38.794539Z","steps":["trace[117368806] 'process raft request' (duration: 104.107261ms)","trace[117368806] 'compare' (duration: 36.283602ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:24:39.236377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.356564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:24:39.236472Z","caller":"traceutil/trace.go:171","msg":"trace[713135294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"324.486137ms","start":"2026-05-07T23:24:38.911957Z","end":"2026-05-07T23:24:39.236443Z","steps":["trace[713135294] 'range keys from in-memory index tree' (duration: 324.256591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:39.236539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:38.911937Z","time spent":"324.58531ms","remote":"127.0.0.1:45570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:24:39.236509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.994515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l2gvt\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-07T23:24:39.236693Z","caller":"traceutil/trace.go:171","msg":"trace[32311978] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l2gvt; range_end:; response_count:1; response_revision:600; }","duration":"275.218221ms","start":"2026-05-07T23:24:38.961447Z","end":"2026-05-07T23:24:39.236665Z","steps":["trace[32311978] 'range keys from in-memory index tree' (duration: 274.821571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:39.807162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639524901659096844,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T23:24:40.307729Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639524901659096844,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T23:24:40.478152Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.195434639s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T23:24:40.536393Z","caller":"traceutil/trace.go:171","msg":"trace[497442956] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.284834102s","start":"2026-05-07T23:24:39.251532Z","end":"2026-05-07T23:24:40.536366Z","steps":["trace[497442956] 'process raft request' (duration: 1.226912213s)","trace[497442956] 'compare' (duration: 57.500268ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:24:40.53654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:39.251499Z","time spent":"1.284959405s","remote":"127.0.0.1:45742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:24:40.541269Z","caller":"traceutil/trace.go:171","msg":"trace[1588828667] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.19096843s","start":"2026-05-07T23:24:39.350251Z","end":"2026-05-07T23:24:40.541219Z","steps":["trace[1588828667] 'process raft request' (duration: 1.190591861s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:40.541398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:39.350227Z","time spent":"1.191108753s","remote":"127.0.0.1:45912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:24:40.569024Z","caller":"traceutil/trace.go:171","msg":"trace[1421807820] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:623; }","duration":"1.262649043s","start":"2026-05-07T23:24:39.306348Z","end":"2026-05-07T23:24:40.568997Z","steps":["trace[1421807820] 'read index received' (duration: 1.172093752s)","trace[1421807820] 'applied index is now lower than readState.Index' (duration: 90.553901ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:24:40.5693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.906813ms","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-05-07T23:24:40.569355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.350594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T23:24:40.569398Z","caller":"traceutil/trace.go:171","msg":"trace[493265988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"846.041736ms","start":"2026-05-07T23:24:39.723318Z","end":"2026-05-07T23:24:40.56936Z","steps":["trace[493265988] 'agreement among raft nodes before linearized reading' (duration: 845.916663ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:40.569409Z","caller":"traceutil/trace.go:171","msg":"trace[656603085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"816.426416ms","start":"2026-05-07T23:24:39.752968Z","end":"2026-05-07T23:24:40.569395Z","steps":["trace[656603085] 'agreement among raft nodes before linearized reading' (duration: 816.230591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:40.569303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26292319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-07T23:24:40.569449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:39.752904Z","time spent":"816.535878ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T23:24:40.569472Z","caller":"traceutil/trace.go:171","msg":"trace[1836855143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"1.263150435s","start":"2026-05-07T23:24:39.306306Z","end":"2026-05-07T23:24:40.569456Z","steps":["trace[1836855143] 'agreement among raft nodes before linearized reading' (duration: 1.262784366s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:40.569474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:39.723296Z","time spent":"846.143359ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T23:24:40.569506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:39.306286Z","time spent":"1.263210806s","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T23:24:40.569708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.002119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:24:40.569798Z","caller":"traceutil/trace.go:171","msg":"trace[1353997729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"659.129812ms","start":"2026-05-07T23:24:39.910654Z","end":"2026-05-07T23:24:40.569784Z","steps":["trace[1353997729] 'agreement among raft nodes before linearized reading' (duration: 658.498017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:40.569821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:39.910631Z","time spent":"659.184453ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T23:24:40.569859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.091375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T23:24:40.569892Z","caller":"traceutil/trace.go:171","msg":"trace[85019246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"848.158326ms","start":"2026-05-07T23:24:39.721724Z","end":"2026-05-07T23:24:40.569882Z","steps":["trace[85019246] 'agreement among raft nodes before linearized reading' (duration: 847.416529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:40.569925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:39.721706Z","time spent":"848.213388ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T23:24:42.339566Z","caller":"traceutil/trace.go:171","msg":"trace[2071140712] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"236.689083ms","start":"2026-05-07T23:24:42.102854Z","end":"2026-05-07T23:24:42.339543Z","steps":["trace[2071140712] 'process raft request' (duration: 236.419237ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:42.345543Z","caller":"traceutil/trace.go:171","msg":"trace[134308723] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"219.674579ms","start":"2026-05-07T23:24:42.125846Z","end":"2026-05-07T23:24:42.345521Z","steps":["trace[134308723] 'process raft request' (duration: 219.398572ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:45.330916Z","caller":"traceutil/trace.go:171","msg":"trace[236504377] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"153.01064ms","start":"2026-05-07T23:24:45.177876Z","end":"2026-05-07T23:24:45.330886Z","steps":["trace[236504377] 'process raft request' (duration: 152.58853ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:52.804456Z","caller":"traceutil/trace.go:171","msg":"trace[775814856] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"156.940815ms","start":"2026-05-07T23:24:52.647492Z","end":"2026-05-07T23:24:52.804433Z","steps":["trace[775814856] 'process raft request' (duration: 156.767811ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:52.804411Z","caller":"traceutil/trace.go:171","msg":"trace[1302861987] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"142.825332ms","start":"2026-05-07T23:24:52.661564Z","end":"2026-05-07T23:24:52.80439Z","steps":["trace[1302861987] 'read index received' (duration: 142.646387ms)","trace[1302861987] 'applied index is now lower than readState.Index' (duration: 178.125µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:24:52.81589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.424858ms","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-05-07T23:24:52.815918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.365022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T23:24:52.815996Z","caller":"traceutil/trace.go:171","msg":"trace[1422316229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:750; }","duration":"154.500105ms","start":"2026-05-07T23:24:52.661477Z","end":"2026-05-07T23:24:52.815977Z","steps":["trace[1422316229] 'agreement among raft nodes before linearized reading' (duration: 143.030996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:52.816036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.677196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T23:24:52.816056Z","caller":"traceutil/trace.go:171","msg":"trace[1157623034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"153.724687ms","start":"2026-05-07T23:24:52.662326Z","end":"2026-05-07T23:24:52.816051Z","steps":["trace[1157623034] 'agreement among raft nodes before linearized reading' (duration: 142.350461ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:24:52.81597Z","caller":"traceutil/trace.go:171","msg":"trace[1519433989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:750; }","duration":"148.544241ms","start":"2026-05-07T23:24:52.667411Z","end":"2026-05-07T23:24:52.815956Z","steps":["trace[1519433989] 'agreement among raft nodes before linearized reading' (duration: 137.290737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:55.312285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.818457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T23:24:55.312389Z","caller":"traceutil/trace.go:171","msg":"trace[767029471] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:767; }","duration":"330.012442ms","start":"2026-05-07T23:24:54.982353Z","end":"2026-05-07T23:24:55.312366Z","steps":["trace[767029471] 'range keys from in-memory index tree' (duration: 329.583632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:55.312441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:54.982329Z","time spent":"330.098754ms","remote":"127.0.0.1:45596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.201\" "} {"level":"warn","ts":"2026-05-07T23:24:55.312443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.89892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ljq74\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-07T23:24:55.312501Z","caller":"traceutil/trace.go:171","msg":"trace[668117225] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ljq74; range_end:; response_count:1; response_revision:767; }","duration":"255.982093ms","start":"2026-05-07T23:24:55.056501Z","end":"2026-05-07T23:24:55.312483Z","steps":["trace[668117225] 'range keys from in-memory index tree' (duration: 255.723286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:55.722949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:55.313336Z","time spent":"409.609518ms","remote":"127.0.0.1:45596","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-07T23:24:55.895116Z","caller":"traceutil/trace.go:171","msg":"trace[666276339] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"574.217688ms","start":"2026-05-07T23:24:55.320881Z","end":"2026-05-07T23:24:55.895099Z","steps":["trace[666276339] 'process raft request' (duration: 574.100155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:24:55.895282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:24:55.320867Z","time spent":"574.344902ms","remote":"127.0.0.1:45742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:24:55.899893Z","caller":"traceutil/trace.go:171","msg":"trace[2110309782] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"175.65147ms","start":"2026-05-07T23:24:55.724224Z","end":"2026-05-07T23:24:55.899876Z","steps":["trace[2110309782] 'process raft request' (duration: 175.550028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:30.744336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.31264ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-07T23:25:30.745162Z","caller":"traceutil/trace.go:171","msg":"trace[533604509] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1015; }","duration":"314.83977ms","start":"2026-05-07T23:25:30.430294Z","end":"2026-05-07T23:25:30.745134Z","steps":["trace[533604509] 'read index received' (duration: 35.021µs)","trace[533604509] 'applied index is now lower than readState.Index' (duration: 314.803019ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:25:30.745219Z","caller":"traceutil/trace.go:171","msg":"trace[1217952218] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"431.255007ms","start":"2026-05-07T23:25:30.313931Z","end":"2026-05-07T23:25:30.745186Z","steps":["trace[1217952218] 'process raft request' (duration: 430.532078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:30.745384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:30.313911Z","time spent":"431.364261ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:25:30.745402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.099707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T23:25:30.745446Z","caller":"traceutil/trace.go:171","msg":"trace[1869267922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:980; }","duration":"315.175139ms","start":"2026-05-07T23:25:30.430256Z","end":"2026-05-07T23:25:30.745431Z","steps":["trace[1869267922] 'agreement among raft nodes before linearized reading' (duration: 314.997734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:30.745486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:30.43024Z","time spent":"315.23616ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T23:25:31.074838Z","caller":"traceutil/trace.go:171","msg":"trace[2138287935] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"164.676351ms","start":"2026-05-07T23:25:30.910135Z","end":"2026-05-07T23:25:31.074811Z","steps":["trace[2138287935] 'read index received' (duration: 160.265271ms)","trace[2138287935] 'applied index is now lower than readState.Index' (duration: 4.40987ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:25:31.075134Z","caller":"traceutil/trace.go:171","msg":"trace[2085512369] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"325.308473ms","start":"2026-05-07T23:25:30.749801Z","end":"2026-05-07T23:25:31.075109Z","steps":["trace[2085512369] 'process raft request' (duration: 320.607566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:31.075322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:30.749785Z","time spent":"325.443107ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:25:31.075407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.273568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:25:31.075453Z","caller":"traceutil/trace.go:171","msg":"trace[788088222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"165.35634ms","start":"2026-05-07T23:25:30.910085Z","end":"2026-05-07T23:25:31.075441Z","steps":["trace[788088222] 'agreement among raft nodes before linearized reading' (duration: 164.98946ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:38.673774Z","caller":"traceutil/trace.go:171","msg":"trace[586896702] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"135.626216ms","start":"2026-05-07T23:25:38.538121Z","end":"2026-05-07T23:25:38.673747Z","steps":["trace[586896702] 'process raft request' (duration: 135.474282ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:38.905214Z","caller":"traceutil/trace.go:171","msg":"trace[844837604] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"132.872971ms","start":"2026-05-07T23:25:38.772312Z","end":"2026-05-07T23:25:38.905185Z","steps":["trace[844837604] 'process raft request' (duration: 108.821701ms)","trace[844837604] 'compare' (duration: 23.818464ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:25:55.595784Z","caller":"traceutil/trace.go:171","msg":"trace[1333544381] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"285.195247ms","start":"2026-05-07T23:25:55.31056Z","end":"2026-05-07T23:25:55.595756Z","steps":["trace[1333544381] 'process raft request' (duration: 285.061724ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:55.595985Z","caller":"traceutil/trace.go:171","msg":"trace[619777241] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"380.376761ms","start":"2026-05-07T23:25:55.21558Z","end":"2026-05-07T23:25:55.595956Z","steps":["trace[619777241] 'process raft request' (duration: 330.092421ms)","trace[619777241] 'compare' (duration: 49.434277ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:25:55.596092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:55.215562Z","time spent":"380.445822ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:25:55.596096Z","caller":"traceutil/trace.go:171","msg":"trace[1991335880] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"291.345133ms","start":"2026-05-07T23:25:55.304707Z","end":"2026-05-07T23:25:55.596052Z","steps":["trace[1991335880] 'read index received' (duration: 241.064994ms)","trace[1991335880] 'applied index is now lower than readState.Index' (duration: 50.276839ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:25:55.596223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.478479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-07T23:25:55.596277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.780495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T23:25:55.596298Z","caller":"traceutil/trace.go:171","msg":"trace[116605949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1062; }","duration":"297.603302ms","start":"2026-05-07T23:25:55.298675Z","end":"2026-05-07T23:25:55.596279Z","steps":["trace[116605949] 'agreement among raft nodes before linearized reading' (duration: 297.383736ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:55.596341Z","caller":"traceutil/trace.go:171","msg":"trace[1856639827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1062; }","duration":"372.846817ms","start":"2026-05-07T23:25:55.223476Z","end":"2026-05-07T23:25:55.596323Z","steps":["trace[1856639827] 'agreement among raft nodes before linearized reading' (duration: 372.686542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:55.596341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.248918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T23:25:55.59637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:55.223411Z","time spent":"372.95316ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T23:25:55.596402Z","caller":"traceutil/trace.go:171","msg":"trace[888483647] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1062; }","duration":"144.369801ms","start":"2026-05-07T23:25:55.452015Z","end":"2026-05-07T23:25:55.596385Z","steps":["trace[888483647] 'agreement among raft nodes before linearized reading' (duration: 144.221467ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:56.045263Z","caller":"traceutil/trace.go:171","msg":"trace[849099668] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"444.696951ms","start":"2026-05-07T23:25:55.600407Z","end":"2026-05-07T23:25:56.045104Z","steps":["trace[849099668] 'process raft request' (duration: 421.469803ms)","trace[849099668] 'compare' (duration: 22.852877ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:25:56.045405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:55.600386Z","time spent":"444.948169ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:25:56.175986Z","caller":"traceutil/trace.go:171","msg":"trace[2130574195] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"575.043975ms","start":"2026-05-07T23:25:55.600917Z","end":"2026-05-07T23:25:56.175961Z","steps":["trace[2130574195] 'process raft request' (duration: 556.199096ms)","trace[2130574195] 'compare' (duration: 18.688305ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:25:56.176196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:55.600897Z","time spent":"575.170458ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:25:56.176327Z","caller":"traceutil/trace.go:171","msg":"trace[506077526] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1104; }","duration":"265.527907ms","start":"2026-05-07T23:25:55.910776Z","end":"2026-05-07T23:25:56.176304Z","steps":["trace[506077526] 'read index received' (duration: 111.124364ms)","trace[506077526] 'applied index is now lower than readState.Index' (duration: 154.401333ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:25:56.17643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.64586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:25:56.176456Z","caller":"traceutil/trace.go:171","msg":"trace[32118472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"265.712192ms","start":"2026-05-07T23:25:55.910738Z","end":"2026-05-07T23:25:56.17645Z","steps":["trace[32118472] 'agreement among raft nodes before linearized reading' (duration: 265.64305ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:56.176609Z","caller":"traceutil/trace.go:171","msg":"trace[2053440989] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"399.328015ms","start":"2026-05-07T23:25:55.777236Z","end":"2026-05-07T23:25:56.176564Z","steps":["trace[2053440989] 'process raft request' (duration: 398.682257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:25:56.176944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:55.777202Z","time spent":"399.584952ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:25:57.93324Z","caller":"traceutil/trace.go:171","msg":"trace[1358400730] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"327.688339ms","start":"2026-05-07T23:25:57.605507Z","end":"2026-05-07T23:25:57.933195Z","steps":["trace[1358400730] 'process raft request' (duration: 298.961491ms)","trace[1358400730] 'compare' (duration: 28.186982ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:25:57.933382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:25:57.605483Z","time spent":"327.821752ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:25:58.318215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.025114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-07T23:25:58.318259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.747163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T23:25:58.318324Z","caller":"traceutil/trace.go:171","msg":"trace[1709028077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1069; }","duration":"137.168958ms","start":"2026-05-07T23:25:58.181127Z","end":"2026-05-07T23:25:58.318296Z","steps":["trace[1709028077] 'range keys from in-memory index tree' (duration: 136.836768ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:58.318327Z","caller":"traceutil/trace.go:171","msg":"trace[1375248821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1069; }","duration":"268.849976ms","start":"2026-05-07T23:25:58.049457Z","end":"2026-05-07T23:25:58.318307Z","steps":["trace[1375248821] 'range keys from in-memory index tree' (duration: 268.505737ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:59.113463Z","caller":"traceutil/trace.go:171","msg":"trace[450486788] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1113; }","duration":"198.356121ms","start":"2026-05-07T23:25:58.915084Z","end":"2026-05-07T23:25:59.11344Z","steps":["trace[450486788] 'read index received' (duration: 198.225517ms)","trace[450486788] 'applied index is now lower than readState.Index' (duration: 129.374µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:25:59.113675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.577668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:25:59.113622Z","caller":"traceutil/trace.go:171","msg":"trace[1220281470] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"221.340272ms","start":"2026-05-07T23:25:58.892243Z","end":"2026-05-07T23:25:59.113583Z","steps":["trace[1220281470] 'process raft request' (duration: 221.042064ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:25:59.113819Z","caller":"traceutil/trace.go:171","msg":"trace[609371961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"198.737472ms","start":"2026-05-07T23:25:58.91507Z","end":"2026-05-07T23:25:59.113807Z","steps":["trace[609371961] 'agreement among raft nodes before linearized reading' (duration: 198.545277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:13.016236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639524901659098120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T23:26:13.444734Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.001980459s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T23:26:13.445103Z","caller":"traceutil/trace.go:171","msg":"trace[2045933329] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"1.002448301s","start":"2026-05-07T23:26:12.44263Z","end":"2026-05-07T23:26:13.445079Z","steps":["trace[2045933329] 'process raft request' (duration: 1.002306007s)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:13.445356Z","caller":"traceutil/trace.go:171","msg":"trace[1737330693] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1215; }","duration":"929.95438ms","start":"2026-05-07T23:26:12.515246Z","end":"2026-05-07T23:26:13.4452Z","steps":["trace[1737330693] 'read index received' (duration: 929.94617ms)","trace[1737330693] 'applied index is now lower than readState.Index' (duration: 6.69µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:13.445626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.36329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T23:26:13.445797Z","caller":"traceutil/trace.go:171","msg":"trace[864421525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1168; }","duration":"930.585425ms","start":"2026-05-07T23:26:12.515197Z","end":"2026-05-07T23:26:13.445783Z","steps":["trace[864421525] 'agreement among raft nodes before linearized reading' (duration: 930.284578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:13.445912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:12.515143Z","time spent":"930.754969ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T23:26:13.445391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:12.442607Z","time spent":"1.002646455s","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:26:13.447038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.912799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:26:13.447446Z","caller":"traceutil/trace.go:171","msg":"trace[662837935] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"226.808467ms","start":"2026-05-07T23:26:13.220359Z","end":"2026-05-07T23:26:13.447168Z","steps":["trace[662837935] 'process raft request' (duration: 226.505239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:13.447352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.897817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:26:13.447769Z","caller":"traceutil/trace.go:171","msg":"trace[908512045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1169; }","duration":"537.336228ms","start":"2026-05-07T23:26:12.91041Z","end":"2026-05-07T23:26:13.447746Z","steps":["trace[908512045] 'agreement among raft nodes before linearized reading' (duration: 536.888277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:13.447832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:12.910369Z","time spent":"537.44579ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T23:26:13.448176Z","caller":"traceutil/trace.go:171","msg":"trace[1240359423] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1169; }","duration":"838.406881ms","start":"2026-05-07T23:26:12.609062Z","end":"2026-05-07T23:26:13.447468Z","steps":["trace[1240359423] 'agreement among raft nodes before linearized reading' (duration: 837.891647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:13.448357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:12.609036Z","time spent":"839.297973ms","remote":"127.0.0.1:45912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-07T23:26:27.151539Z","caller":"traceutil/trace.go:171","msg":"trace[1358128776] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"103.208823ms","start":"2026-05-07T23:26:27.048311Z","end":"2026-05-07T23:26:27.15152Z","steps":["trace[1358128776] 'process raft request' (duration: 103.09795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:27.95424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.644548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:26:27.954317Z","caller":"traceutil/trace.go:171","msg":"trace[1046548486] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"434.102344ms","start":"2026-05-07T23:26:27.520203Z","end":"2026-05-07T23:26:27.954305Z","steps":["trace[1046548486] 'read index received' (duration: 118.202749ms)","trace[1046548486] 'applied index is now lower than readState.Index' (duration: 315.898625ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:27.954363Z","caller":"traceutil/trace.go:171","msg":"trace[1504965158] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"466.256151ms","start":"2026-05-07T23:26:27.488071Z","end":"2026-05-07T23:26:27.954328Z","steps":["trace[1504965158] 'process raft request' (duration: 150.466259ms)","trace[1504965158] 'compare' (duration: 315.560526ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:27.954442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.236367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-07T23:26:27.95445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.096401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T23:26:27.954473Z","caller":"traceutil/trace.go:171","msg":"trace[88121460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1215; }","duration":"434.294029ms","start":"2026-05-07T23:26:27.52017Z","end":"2026-05-07T23:26:27.954464Z","steps":["trace[88121460] 'agreement among raft nodes before linearized reading' (duration: 434.162855ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:27.954478Z","caller":"traceutil/trace.go:171","msg":"trace[1525489798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"164.155573ms","start":"2026-05-07T23:26:27.790315Z","end":"2026-05-07T23:26:27.954471Z","steps":["trace[1525489798] 'agreement among raft nodes before linearized reading' (duration: 164.075001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:27.954479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.515214ms","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-05-07T23:26:27.954495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.520158Z","time spent":"434.332459ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T23:26:27.954498Z","caller":"traceutil/trace.go:171","msg":"trace[110822814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1215; }","duration":"428.568185ms","start":"2026-05-07T23:26:27.525924Z","end":"2026-05-07T23:26:27.954492Z","steps":["trace[110822814] 'agreement among raft nodes before linearized reading' (duration: 428.471313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:27.954518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.525909Z","time spent":"428.604846ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T23:26:27.954532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.48805Z","time spent":"466.403304ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:26:27.95457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.914009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T23:26:27.954617Z","caller":"traceutil/trace.go:171","msg":"trace[382046739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1215; }","duration":"305.021372ms","start":"2026-05-07T23:26:27.649582Z","end":"2026-05-07T23:26:27.954603Z","steps":["trace[382046739] 'agreement among raft nodes before linearized reading' (duration: 304.859648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:27.954649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:27.649548Z","time spent":"305.094903ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T23:26:28.088609Z","caller":"traceutil/trace.go:171","msg":"trace[757446852] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"130.924979ms","start":"2026-05-07T23:26:27.957657Z","end":"2026-05-07T23:26:28.088582Z","steps":["trace[757446852] 'process raft request' (duration: 129.608016ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:28.089109Z","caller":"traceutil/trace.go:171","msg":"trace[269296508] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"131.37118ms","start":"2026-05-07T23:26:27.957721Z","end":"2026-05-07T23:26:28.089093Z","steps":["trace[269296508] 'process raft request' (duration: 131.068873ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:28.08923Z","caller":"traceutil/trace.go:171","msg":"trace[2108115778] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"131.136734ms","start":"2026-05-07T23:26:27.958071Z","end":"2026-05-07T23:26:28.089208Z","steps":["trace[2108115778] 'process raft request' (duration: 130.935699ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:28.089223Z","caller":"traceutil/trace.go:171","msg":"trace[1727918276] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"130.848656ms","start":"2026-05-07T23:26:27.958343Z","end":"2026-05-07T23:26:28.089191Z","steps":["trace[1727918276] 'process raft request' (duration: 130.727213ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:31.535861Z","caller":"traceutil/trace.go:171","msg":"trace[524672139] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"510.783451ms","start":"2026-05-07T23:26:31.025058Z","end":"2026-05-07T23:26:31.535842Z","steps":["trace[524672139] 'process raft request' (duration: 510.584856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:31.536024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:31.025033Z","time spent":"510.906424ms","remote":"127.0.0.1:45730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:26:31.968034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.834622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:26:31.96811Z","caller":"traceutil/trace.go:171","msg":"trace[1789629388] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"109.189723ms","start":"2026-05-07T23:26:31.858908Z","end":"2026-05-07T23:26:31.968098Z","steps":["trace[1789629388] 'read index received' (duration: 46.791µs)","trace[1789629388] 'applied index is now lower than readState.Index' (duration: 109.141802ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:31.968234Z","caller":"traceutil/trace.go:171","msg":"trace[952400389] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"425.541749ms","start":"2026-05-07T23:26:31.542683Z","end":"2026-05-07T23:26:31.968225Z","steps":["trace[952400389] 'process raft request' (duration: 117.45414ms)","trace[952400389] 'compare' (duration: 307.74751ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:31.968275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:31.542667Z","time spent":"425.58829ms","remote":"127.0.0.1:45960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:26:31.968332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.382357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:26:31.968382Z","caller":"traceutil/trace.go:171","msg":"trace[1512925398] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1247; }","duration":"109.51333ms","start":"2026-05-07T23:26:31.858857Z","end":"2026-05-07T23:26:31.96837Z","steps":["trace[1512925398] 'agreement among raft nodes before linearized reading' (duration: 109.400628ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:32.371374Z","caller":"traceutil/trace.go:171","msg":"trace[1473519509] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1299; }","duration":"208.328134ms","start":"2026-05-07T23:26:32.163022Z","end":"2026-05-07T23:26:32.37135Z","steps":["trace[1473519509] 'read index received' (duration: 21.606333ms)","trace[1473519509] 'applied index is now lower than readState.Index' (duration: 186.720751ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:32.371444Z","caller":"traceutil/trace.go:171","msg":"trace[490729619] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"250.333677ms","start":"2026-05-07T23:26:32.121077Z","end":"2026-05-07T23:26:32.371411Z","steps":["trace[490729619] 'process raft request' (duration: 250.198514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:32.371589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.542969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T23:26:32.371561Z","caller":"traceutil/trace.go:171","msg":"trace[17790191] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"250.450451ms","start":"2026-05-07T23:26:32.121018Z","end":"2026-05-07T23:26:32.371468Z","steps":["trace[17790191] 'process raft request' (duration: 250.110152ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:32.371636Z","caller":"traceutil/trace.go:171","msg":"trace[977943006] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"250.504681ms","start":"2026-05-07T23:26:32.121119Z","end":"2026-05-07T23:26:32.371623Z","steps":["trace[977943006] 'process raft request' (duration: 250.192214ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:32.371643Z","caller":"traceutil/trace.go:171","msg":"trace[38039686] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1252; }","duration":"208.641991ms","start":"2026-05-07T23:26:32.16299Z","end":"2026-05-07T23:26:32.371632Z","steps":["trace[38039686] 'agreement among raft nodes before linearized reading' (duration: 208.454506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:33.121444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.20568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:26:33.121521Z","caller":"traceutil/trace.go:171","msg":"trace[1950970913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1254; }","duration":"210.315782ms","start":"2026-05-07T23:26:32.91118Z","end":"2026-05-07T23:26:33.121495Z","steps":["trace[1950970913] 'range keys from in-memory index tree' (duration: 210.141238ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:34.372967Z","caller":"traceutil/trace.go:171","msg":"trace[416622090] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"183.718214ms","start":"2026-05-07T23:26:34.189232Z","end":"2026-05-07T23:26:34.37295Z","steps":["trace[416622090] 'read index received' (duration: 183.596881ms)","trace[416622090] 'applied index is now lower than readState.Index' (duration: 120.753µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:34.373016Z","caller":"traceutil/trace.go:171","msg":"trace[1330401507] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"378.658731ms","start":"2026-05-07T23:26:33.994331Z","end":"2026-05-07T23:26:34.372989Z","steps":["trace[1330401507] 'process raft request' (duration: 378.510667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:34.373156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:33.994314Z","time spent":"378.775073ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:26:34.373177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.938809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-07T23:26:34.373316Z","caller":"traceutil/trace.go:171","msg":"trace[1091451842] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1261; }","duration":"184.091953ms","start":"2026-05-07T23:26:34.189217Z","end":"2026-05-07T23:26:34.373309Z","steps":["trace[1091451842] 'agreement among raft nodes before linearized reading' (duration: 183.843847ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:34.514541Z","caller":"traceutil/trace.go:171","msg":"trace[178588474] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1313; }","duration":"123.186205ms","start":"2026-05-07T23:26:34.391337Z","end":"2026-05-07T23:26:34.514523Z","steps":["trace[178588474] 'read index received' (duration: 67.765312ms)","trace[178588474] 'applied index is now lower than readState.Index' (duration: 55.420233ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:26:34.514821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.464411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T23:26:34.514852Z","caller":"traceutil/trace.go:171","msg":"trace[283518120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1265; }","duration":"123.512123ms","start":"2026-05-07T23:26:34.391331Z","end":"2026-05-07T23:26:34.514844Z","steps":["trace[283518120] 'agreement among raft nodes before linearized reading' (duration: 123.264307ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:34.515231Z","caller":"traceutil/trace.go:171","msg":"trace[655195000] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"135.810391ms","start":"2026-05-07T23:26:34.379409Z","end":"2026-05-07T23:26:34.515219Z","steps":["trace[655195000] 'process raft request' (duration: 79.64226ms)","trace[655195000] 'compare' (duration: 55.272919ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:34.515418Z","caller":"traceutil/trace.go:171","msg":"trace[1324964272] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"135.414162ms","start":"2026-05-07T23:26:34.379994Z","end":"2026-05-07T23:26:34.515408Z","steps":["trace[1324964272] 'process raft request' (duration: 134.472298ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:34.515538Z","caller":"traceutil/trace.go:171","msg":"trace[1718233183] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"135.511384ms","start":"2026-05-07T23:26:34.380018Z","end":"2026-05-07T23:26:34.515529Z","steps":["trace[1718233183] 'process raft request' (duration: 134.477508ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:44.827209Z","caller":"traceutil/trace.go:171","msg":"trace[101283227] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"398.072088ms","start":"2026-05-07T23:26:44.429099Z","end":"2026-05-07T23:26:44.827171Z","steps":["trace[101283227] 'read index received' (duration: 397.827182ms)","trace[101283227] 'applied index is now lower than readState.Index' (duration: 242.946µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:26:44.827299Z","caller":"traceutil/trace.go:171","msg":"trace[576511449] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"427.431876ms","start":"2026-05-07T23:26:44.399833Z","end":"2026-05-07T23:26:44.827265Z","steps":["trace[576511449] 'process raft request' (duration: 427.064617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:44.827457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:44.399813Z","time spent":"427.560109ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:26:44.827587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.442468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T23:26:44.827652Z","caller":"traceutil/trace.go:171","msg":"trace[1158658399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1335; }","duration":"398.53995ms","start":"2026-05-07T23:26:44.429079Z","end":"2026-05-07T23:26:44.827619Z","steps":["trace[1158658399] 'agreement among raft nodes before linearized reading' (duration: 398.293934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:44.827694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:26:44.429071Z","time spent":"398.612902ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T23:26:44.827893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.422142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:26:44.827917Z","caller":"traceutil/trace.go:171","msg":"trace[877313937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1335; }","duration":"229.445193ms","start":"2026-05-07T23:26:44.598463Z","end":"2026-05-07T23:26:44.827909Z","steps":["trace[877313937] 'agreement among raft nodes before linearized reading' (duration: 229.365861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:44.828061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.715717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-07T23:26:44.828097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.218208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T23:26:44.828142Z","caller":"traceutil/trace.go:171","msg":"trace[900081646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1335; }","duration":"203.83336ms","start":"2026-05-07T23:26:44.624282Z","end":"2026-05-07T23:26:44.828115Z","steps":["trace[900081646] 'agreement among raft nodes before linearized reading' (duration: 203.633254ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:44.82818Z","caller":"traceutil/trace.go:171","msg":"trace[1214645294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1335; }","duration":"231.355542ms","start":"2026-05-07T23:26:44.596805Z","end":"2026-05-07T23:26:44.828161Z","steps":["trace[1214645294] 'agreement among raft nodes before linearized reading' (duration: 231.091754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:26:44.828389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.316892ms","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-05-07T23:26:44.82843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.126446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T23:26:44.828799Z","caller":"traceutil/trace.go:171","msg":"trace[524678078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1335; }","duration":"231.482845ms","start":"2026-05-07T23:26:44.597273Z","end":"2026-05-07T23:26:44.828755Z","steps":["trace[524678078] 'agreement among raft nodes before linearized reading' (duration: 230.597662ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:26:44.829201Z","caller":"traceutil/trace.go:171","msg":"trace[1530980425] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1335; }","duration":"239.546097ms","start":"2026-05-07T23:26:44.588989Z","end":"2026-05-07T23:26:44.828535Z","steps":["trace[1530980425] 'agreement among raft nodes before linearized reading' (duration: 238.759977ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:27:35.109661Z","caller":"traceutil/trace.go:171","msg":"trace[1366756774] transaction","detail":"{read_only:false; response_revision:1566; number_of_response:1; }","duration":"112.716824ms","start":"2026-05-07T23:27:34.996923Z","end":"2026-05-07T23:27:35.10964Z","steps":["trace[1366756774] 'process raft request' (duration: 80.615115ms)","trace[1366756774] 'compare' (duration: 31.869013ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:29:33.972996Z","caller":"traceutil/trace.go:171","msg":"trace[809582506] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"123.484745ms","start":"2026-05-07T23:29:33.849489Z","end":"2026-05-07T23:29:33.972973Z","steps":["trace[809582506] 'process raft request' (duration: 120.780848ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:29:33.973361Z","caller":"traceutil/trace.go:171","msg":"trace[1077928036] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"123.801623ms","start":"2026-05-07T23:29:33.849532Z","end":"2026-05-07T23:29:33.973334Z","steps":["trace[1077928036] 'process raft request' (duration: 123.405633ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:29:33.973568Z","caller":"traceutil/trace.go:171","msg":"trace[1466917882] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"122.658834ms","start":"2026-05-07T23:29:33.850896Z","end":"2026-05-07T23:29:33.973555Z","steps":["trace[1466917882] 'process raft request' (duration: 122.260514ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:29:33.973571Z","caller":"traceutil/trace.go:171","msg":"trace[1085316364] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"121.679051ms","start":"2026-05-07T23:29:33.851853Z","end":"2026-05-07T23:29:33.973532Z","steps":["trace[1085316364] 'process raft request' (duration: 121.528217ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:29:43.87148Z","caller":"traceutil/trace.go:171","msg":"trace[719513044] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"254.605381ms","start":"2026-05-07T23:29:43.616845Z","end":"2026-05-07T23:29:43.871451Z","steps":["trace[719513044] 'process raft request' (duration: 254.423757ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:01.805254Z","caller":"traceutil/trace.go:171","msg":"trace[378215598] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"267.560354ms","start":"2026-05-07T23:30:01.537671Z","end":"2026-05-07T23:30:01.805231Z","steps":["trace[378215598] 'process raft request' (duration: 267.388ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:01.854862Z","caller":"traceutil/trace.go:171","msg":"trace[1312116673] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"173.151993ms","start":"2026-05-07T23:30:01.681682Z","end":"2026-05-07T23:30:01.854834Z","steps":["trace[1312116673] 'process raft request' (duration: 142.969271ms)","trace[1312116673] 'compare' (duration: 30.062569ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:06.448504Z","caller":"traceutil/trace.go:171","msg":"trace[1762641264] linearizableReadLoop","detail":"{readStateIndex:2390; appliedIndex:2389; }","duration":"473.927793ms","start":"2026-05-07T23:30:05.974551Z","end":"2026-05-07T23:30:06.448478Z","steps":["trace[1762641264] 'read index received' (duration: 473.771709ms)","trace[1762641264] 'applied index is now lower than readState.Index' (duration: 155.034µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:06.448612Z","caller":"traceutil/trace.go:171","msg":"trace[1258166434] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"513.035006ms","start":"2026-05-07T23:30:05.935544Z","end":"2026-05-07T23:30:06.448579Z","steps":["trace[1258166434] 'process raft request' (duration: 512.79676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:06.448789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:05.93553Z","time spent":"513.17784ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:30:06.448801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.2353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-07T23:30:06.44884Z","caller":"traceutil/trace.go:171","msg":"trace[1076091247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2294; }","duration":"474.317942ms","start":"2026-05-07T23:30:05.974512Z","end":"2026-05-07T23:30:06.44883Z","steps":["trace[1076091247] 'agreement among raft nodes before linearized reading' (duration: 474.109487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:06.448881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:05.974496Z","time spent":"474.377003ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T23:30:06.449039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.494343ms","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:531"} {"level":"info","ts":"2026-05-07T23:30:06.449072Z","caller":"traceutil/trace.go:171","msg":"trace[1229015705] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2294; }","duration":"255.562075ms","start":"2026-05-07T23:30:06.193502Z","end":"2026-05-07T23:30:06.449064Z","steps":["trace[1229015705] 'agreement among raft nodes before linearized reading' (duration: 255.479323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:06.449229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.339777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-07T23:30:06.449273Z","caller":"traceutil/trace.go:171","msg":"trace[1897250442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2294; }","duration":"440.438789ms","start":"2026-05-07T23:30:06.008822Z","end":"2026-05-07T23:30:06.449261Z","steps":["trace[1897250442] 'agreement among raft nodes before linearized reading' (duration: 440.268185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:06.44931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:06.008802Z","time spent":"440.499651ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T23:30:06.449255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.704235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T23:30:06.449516Z","caller":"traceutil/trace.go:171","msg":"trace[1199304975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2294; }","duration":"297.983302ms","start":"2026-05-07T23:30:06.151486Z","end":"2026-05-07T23:30:06.449469Z","steps":["trace[1199304975] 'agreement among raft nodes before linearized reading' (duration: 297.470109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:06.44945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.150132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-07T23:30:06.449648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.013229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:30:06.449688Z","caller":"traceutil/trace.go:171","msg":"trace[315531508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2294; }","duration":"287.429868ms","start":"2026-05-07T23:30:06.162244Z","end":"2026-05-07T23:30:06.449674Z","steps":["trace[315531508] 'agreement among raft nodes before linearized reading' (duration: 286.766662ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:06.449789Z","caller":"traceutil/trace.go:171","msg":"trace[473194549] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:2294; }","duration":"473.870521ms","start":"2026-05-07T23:30:05.975816Z","end":"2026-05-07T23:30:06.449687Z","steps":["trace[473194549] 'agreement among raft nodes before linearized reading' (duration: 472.957808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:06.449963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:05.975802Z","time spent":"474.146228ms","remote":"127.0.0.1:45642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" "} {"level":"warn","ts":"2026-05-07T23:30:06.450211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.402702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T23:30:06.450403Z","caller":"traceutil/trace.go:171","msg":"trace[1838377460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2294; }","duration":"298.688859ms","start":"2026-05-07T23:30:06.151592Z","end":"2026-05-07T23:30:06.450281Z","steps":["trace[1838377460] 'agreement among raft nodes before linearized reading' (duration: 297.362486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:06.713969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.044123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:30:06.714268Z","caller":"traceutil/trace.go:171","msg":"trace[885924128] transaction","detail":"{read_only:false; response_revision:2298; number_of_response:1; }","duration":"259.223766ms","start":"2026-05-07T23:30:06.455025Z","end":"2026-05-07T23:30:06.714249Z","steps":["trace[885924128] 'process raft request' (duration: 259.154214ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:06.714373Z","caller":"traceutil/trace.go:171","msg":"trace[2443941] transaction","detail":"{read_only:false; response_revision:2297; number_of_response:1; }","duration":"259.388709ms","start":"2026-05-07T23:30:06.454954Z","end":"2026-05-07T23:30:06.714343Z","steps":["trace[2443941] 'process raft request' (duration: 259.190404ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:06.714322Z","caller":"traceutil/trace.go:171","msg":"trace[36196984] transaction","detail":"{read_only:false; response_revision:2296; number_of_response:1; }","duration":"259.552653ms","start":"2026-05-07T23:30:06.454749Z","end":"2026-05-07T23:30:06.714301Z","steps":["trace[36196984] 'process raft request' (duration: 259.335788ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:06.714569Z","caller":"traceutil/trace.go:171","msg":"trace[392880672] transaction","detail":"{read_only:false; response_revision:2299; number_of_response:1; }","duration":"259.203915ms","start":"2026-05-07T23:30:06.455353Z","end":"2026-05-07T23:30:06.714557Z","steps":["trace[392880672] 'process raft request' (duration: 258.860787ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:06.714688Z","caller":"traceutil/trace.go:171","msg":"trace[1478292656] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"259.852601ms","start":"2026-05-07T23:30:06.454731Z","end":"2026-05-07T23:30:06.714584Z","steps":["trace[1478292656] 'process raft request' (duration: 132.08795ms)","trace[1478292656] 'compare' (duration: 126.871209ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:06.714942Z","caller":"traceutil/trace.go:171","msg":"trace[1655801351] linearizableReadLoop","detail":"{readStateIndex:2396; appliedIndex:2390; }","duration":"212.624034ms","start":"2026-05-07T23:30:06.502288Z","end":"2026-05-07T23:30:06.714912Z","steps":["trace[1655801351] 'read index received' (duration: 84.532755ms)","trace[1655801351] 'applied index is now lower than readState.Index' (duration: 128.088649ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:06.714961Z","caller":"traceutil/trace.go:171","msg":"trace[685925336] transaction","detail":"{read_only:false; response_revision:2300; number_of_response:1; }","duration":"259.570914ms","start":"2026-05-07T23:30:06.455378Z","end":"2026-05-07T23:30:06.714949Z","steps":["trace[685925336] 'process raft request' (duration: 259.136043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:06.715388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.086557ms","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:5 size:1050264"} {"level":"info","ts":"2026-05-07T23:30:06.715433Z","caller":"traceutil/trace.go:171","msg":"trace[1827557882] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:2300; }","duration":"213.175969ms","start":"2026-05-07T23:30:06.502243Z","end":"2026-05-07T23:30:06.715419Z","steps":["trace[1827557882] 'agreement among raft nodes before linearized reading' (duration: 212.750108ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:14.870858Z","caller":"traceutil/trace.go:171","msg":"trace[2012158658] linearizableReadLoop","detail":"{readStateIndex:2430; appliedIndex:2429; }","duration":"271.168614ms","start":"2026-05-07T23:30:14.599667Z","end":"2026-05-07T23:30:14.870835Z","steps":["trace[2012158658] 'read index received' (duration: 270.972559ms)","trace[2012158658] 'applied index is now lower than readState.Index' (duration: 195.005µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:14.870909Z","caller":"traceutil/trace.go:171","msg":"trace[140755830] transaction","detail":"{read_only:false; response_revision:2333; number_of_response:1; }","duration":"304.673418ms","start":"2026-05-07T23:30:14.566201Z","end":"2026-05-07T23:30:14.870875Z","steps":["trace[140755830] 'process raft request' (duration: 304.492184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:14.871028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.339077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:759"} {"level":"warn","ts":"2026-05-07T23:30:14.871024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.339898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replication-controller\" ","response":"range_response_count:1 size:828"} {"level":"info","ts":"2026-05-07T23:30:14.871068Z","caller":"traceutil/trace.go:171","msg":"trace[2083897636] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:2333; }","duration":"226.424599ms","start":"2026-05-07T23:30:14.64463Z","end":"2026-05-07T23:30:14.871055Z","steps":["trace[2083897636] 'agreement among raft nodes before linearized reading' (duration: 226.332937ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:14.871092Z","caller":"traceutil/trace.go:171","msg":"trace[2087264506] range","detail":"{range_begin:/registry/clusterroles/system:controller:replication-controller; range_end:; response_count:1; response_revision:2333; }","duration":"271.459911ms","start":"2026-05-07T23:30:14.599613Z","end":"2026-05-07T23:30:14.871073Z","steps":["trace[2087264506] 'agreement among raft nodes before linearized reading' (duration: 271.303747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:14.871085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:14.566175Z","time spent":"304.819432ms","remote":"127.0.0.1:45730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6937,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:30:14.87132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.458325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-07T23:30:14.871395Z","caller":"traceutil/trace.go:171","msg":"trace[646154994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2333; }","duration":"113.61675ms","start":"2026-05-07T23:30:14.757761Z","end":"2026-05-07T23:30:14.871377Z","steps":["trace[646154994] 'agreement among raft nodes before linearized reading' (duration: 113.359844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:15.320511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.293256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:30:15.321019Z","caller":"traceutil/trace.go:171","msg":"trace[137730208] transaction","detail":"{read_only:false; response_revision:2335; number_of_response:1; }","duration":"443.385113ms","start":"2026-05-07T23:30:14.877601Z","end":"2026-05-07T23:30:15.320986Z","steps":["trace[137730208] 'process raft request' (duration: 220.538552ms)","trace[137730208] 'compare' (duration: 222.177214ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:30:15.321172Z","caller":"traceutil/trace.go:171","msg":"trace[223829976] transaction","detail":"{read_only:false; response_revision:2337; number_of_response:1; }","duration":"443.122267ms","start":"2026-05-07T23:30:14.878035Z","end":"2026-05-07T23:30:15.321158Z","steps":["trace[223829976] 'process raft request' (duration: 442.918812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:15.321263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:14.878028Z","time spent":"443.198509ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:30:15.321306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:14.877577Z","time spent":"443.524547ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:30:15.321385Z","caller":"traceutil/trace.go:171","msg":"trace[269526540] transaction","detail":"{read_only:false; response_revision:2336; number_of_response:1; }","duration":"443.153858ms","start":"2026-05-07T23:30:14.877877Z","end":"2026-05-07T23:30:15.321031Z","steps":["trace[269526540] 'process raft request' (duration: 442.994283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:15.321439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:14.877861Z","time spent":"443.552357ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:30:15.322302Z","caller":"traceutil/trace.go:171","msg":"trace[183082693] transaction","detail":"{read_only:false; response_revision:2338; number_of_response:1; }","duration":"444.05368ms","start":"2026-05-07T23:30:14.878222Z","end":"2026-05-07T23:30:15.322275Z","steps":["trace[183082693] 'process raft request' (duration: 442.882621ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:30:15.322492Z","caller":"traceutil/trace.go:171","msg":"trace[1271336377] linearizableReadLoop","detail":"{readStateIndex:2436; appliedIndex:2430; }","duration":"412.40649ms","start":"2026-05-07T23:30:14.910061Z","end":"2026-05-07T23:30:15.322467Z","steps":["trace[1271336377] 'read index received' (duration: 16.966772ms)","trace[1271336377] 'applied index is now lower than readState.Index' (duration: 395.438298ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:30:15.322578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:14.878197Z","time spent":"444.278526ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:30:15.322698Z","caller":"traceutil/trace.go:171","msg":"trace[241581927] transaction","detail":"{read_only:false; response_revision:2339; number_of_response:1; }","duration":"443.407873ms","start":"2026-05-07T23:30:14.879278Z","end":"2026-05-07T23:30:15.322686Z","steps":["trace[241581927] 'process raft request' (duration: 442.918211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:15.323101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.362319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-05-07T23:30:15.323167Z","caller":"traceutil/trace.go:171","msg":"trace[401262616] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:2339; }","duration":"373.460621ms","start":"2026-05-07T23:30:14.949686Z","end":"2026-05-07T23:30:15.323146Z","steps":["trace[401262616] 'agreement among raft nodes before linearized reading' (duration: 373.358208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:15.323218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:14.949666Z","time spent":"373.540212ms","remote":"127.0.0.1:45912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":782,"request content":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" "} {"level":"warn","ts":"2026-05-07T23:30:15.323239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.24212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T23:30:15.323281Z","caller":"traceutil/trace.go:171","msg":"trace[1498716447] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:2339; }","duration":"321.304021ms","start":"2026-05-07T23:30:15.001966Z","end":"2026-05-07T23:30:15.32327Z","steps":["trace[1498716447] 'agreement among raft nodes before linearized reading' (duration: 321.165528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:15.323319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:15.001959Z","time spent":"321.353292ms","remote":"127.0.0.1:45596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.201\" "} {"level":"warn","ts":"2026-05-07T23:30:15.323451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.262645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:deployment-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-05-07T23:30:15.323511Z","caller":"traceutil/trace.go:171","msg":"trace[1624961204] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:deployment-controller; range_end:; response_count:1; response_revision:2339; }","duration":"369.330598ms","start":"2026-05-07T23:30:14.954163Z","end":"2026-05-07T23:30:15.323494Z","steps":["trace[1624961204] 'agreement among raft nodes before linearized reading' (duration: 369.242875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:15.323561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:14.954155Z","time spent":"369.394529ms","remote":"127.0.0.1:45912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":782,"request content":"key:\"/registry/clusterrolebindings/system:controller:deployment-controller\" "} {"level":"warn","ts":"2026-05-07T23:30:15.323164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:14.87927Z","time spent":"443.815023ms","remote":"127.0.0.1:45826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:30:15.324182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.114003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:30:15.324242Z","caller":"traceutil/trace.go:171","msg":"trace[1222713579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2339; }","duration":"414.189125ms","start":"2026-05-07T23:30:14.910031Z","end":"2026-05-07T23:30:15.32422Z","steps":["trace[1222713579] 'agreement among raft nodes before linearized reading' (duration: 413.17867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:30:15.32427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:30:14.910022Z","time spent":"414.242716ms","remote":"127.0.0.1:45572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}