{"level":"warn","ts":"2026-04-16T06:38:18.274589Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T06:38:18.274688Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-16T06:38:18.274765Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T06:38:18.274781Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-16T06:38:18.274802Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T06:38:18.275728Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-16T06:38:18.275834Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-16T06:38:18.280328Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.217258ms"} {"level":"info","ts":"2026-04-16T06:38:18.28839Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-16T06:38:18.288488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T06:38:18.288518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-16T06:38:18.28853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T06:38:18.288547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-16T06:38:18.288589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-16T06:38:18.300287Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T06:38:18.302717Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T06:38:18.305493Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-16T06:38:18.310075Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T06:38:18.310209Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T06:38:18.310569Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T06:38:18.310742Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T06:38:18.310787Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T06:38:18.31116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-16T06:38:18.314552Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-16T06:38:18.316214Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T06:38:18.316299Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-16T06:38:18.316333Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-16T06:38:18.316501Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T06:38:18.316552Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T06:38:18.389113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T06:38:18.389343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T06:38:18.389441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-16T06:38:18.38957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-16T06:38:18.38968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-16T06:38:18.389794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-16T06:38:18.389888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-16T06:38:18.390757Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:38:18.391477Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T06:38:18.39162Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T06:38:18.391656Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T06:38:18.392064Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T06:38:18.392121Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T06:38:18.392237Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:38:18.392425Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:38:18.392562Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:38:18.395038Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T06:38:18.399194Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-04-16T06:38:39.851324Z","caller":"traceutil/trace.go:171","msg":"trace[51740900] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"103.304516ms","start":"2026-04-16T06:38:39.747992Z","end":"2026-04-16T06:38:39.851297Z","steps":["trace[51740900] 'process raft request' (duration: 103.152062ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:42.970536Z","caller":"traceutil/trace.go:171","msg":"trace[1857314024] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"224.104398ms","start":"2026-04-16T06:38:42.746406Z","end":"2026-04-16T06:38:42.970511Z","steps":["trace[1857314024] 'process raft request' (duration: 224.053307ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:42.970546Z","caller":"traceutil/trace.go:171","msg":"trace[986991647] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"224.399425ms","start":"2026-04-16T06:38:42.746119Z","end":"2026-04-16T06:38:42.970518Z","steps":["trace[986991647] 'process raft request' (duration: 215.380791ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:42.971125Z","caller":"traceutil/trace.go:171","msg":"trace[636611882] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"224.749986ms","start":"2026-04-16T06:38:42.746348Z","end":"2026-04-16T06:38:42.971099Z","steps":["trace[636611882] 'process raft request' (duration: 224.047816ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:42.972672Z","caller":"traceutil/trace.go:171","msg":"trace[1770091275] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"221.675299ms","start":"2026-04-16T06:38:42.750954Z","end":"2026-04-16T06:38:42.972629Z","steps":["trace[1770091275] 'process raft request' (duration: 221.181435ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:42.972956Z","caller":"traceutil/trace.go:171","msg":"trace[731867011] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"216.733401ms","start":"2026-04-16T06:38:42.756213Z","end":"2026-04-16T06:38:42.972946Z","steps":["trace[731867011] 'process raft request' (duration: 215.98212ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:42.973985Z","caller":"traceutil/trace.go:171","msg":"trace[1810669044] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:455; }","duration":"222.926435ms","start":"2026-04-16T06:38:42.751041Z","end":"2026-04-16T06:38:42.973967Z","steps":["trace[1810669044] 'read index received' (duration: 210.562336ms)","trace[1810669044] 'applied index is now lower than readState.Index' (duration: 12.363378ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:38:42.974262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.194732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-thfmc\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-04-16T06:38:42.974368Z","caller":"traceutil/trace.go:171","msg":"trace[1408359735] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-thfmc; range_end:; response_count:1; response_revision:447; }","duration":"223.336737ms","start":"2026-04-16T06:38:42.75101Z","end":"2026-04-16T06:38:42.974347Z","steps":["trace[1408359735] 'agreement among raft nodes before linearized reading' (duration: 223.11149ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:42.974461Z","caller":"traceutil/trace.go:171","msg":"trace[588526955] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"170.738516ms","start":"2026-04-16T06:38:42.803685Z","end":"2026-04-16T06:38:42.974424Z","steps":["trace[588526955] 'process raft request' (duration: 168.75674ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:45.432693Z","caller":"traceutil/trace.go:171","msg":"trace[383167090] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"892.905665ms","start":"2026-04-16T06:38:44.539747Z","end":"2026-04-16T06:38:45.432653Z","steps":["trace[383167090] 'process raft request' (duration: 839.589462ms)","trace[383167090] 'compare' (duration: 52.521341ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:38:45.432733Z","caller":"traceutil/trace.go:171","msg":"trace[1650328120] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:489; }","duration":"869.857427ms","start":"2026-04-16T06:38:44.562852Z","end":"2026-04-16T06:38:45.43271Z","steps":["trace[1650328120] 'read index received' (duration: 16.382533ms)","trace[1650328120] 'applied index is now lower than readState.Index' (duration: 853.474114ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:38:45.432811Z","caller":"traceutil/trace.go:171","msg":"trace[801797113] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"890.92631ms","start":"2026-04-16T06:38:44.541877Z","end":"2026-04-16T06:38:45.432803Z","steps":["trace[801797113] 'process raft request' (duration: 890.599981ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:45.432977Z","caller":"traceutil/trace.go:171","msg":"trace[856929835] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"882.207599ms","start":"2026-04-16T06:38:44.550762Z","end":"2026-04-16T06:38:45.43297Z","steps":["trace[856929835] 'process raft request' (duration: 881.756957ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:45.432719Z","caller":"traceutil/trace.go:171","msg":"trace[1542938084] transaction","detail":"{read_only:false; number_of_response:1; response_revision:476; }","duration":"891.676592ms","start":"2026-04-16T06:38:44.541013Z","end":"2026-04-16T06:38:45.432689Z","steps":["trace[1542938084] 'process raft request' (duration: 891.337253ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:45.434102Z","caller":"traceutil/trace.go:171","msg":"trace[1846380815] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"878.692961ms","start":"2026-04-16T06:38:44.55534Z","end":"2026-04-16T06:38:45.434033Z","steps":["trace[1846380815] 'process raft request' (duration: 877.248582ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:45.434421Z","caller":"traceutil/trace.go:171","msg":"trace[753695895] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"856.164088ms","start":"2026-04-16T06:38:44.577919Z","end":"2026-04-16T06:38:45.434084Z","steps":["trace[753695895] 'process raft request' (duration: 854.728589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:45.43478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.910203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T06:38:45.434862Z","caller":"traceutil/trace.go:171","msg":"trace[1088769927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:480; }","duration":"872.015986ms","start":"2026-04-16T06:38:44.562823Z","end":"2026-04-16T06:38:45.434839Z","steps":["trace[1088769927] 'agreement among raft nodes before linearized reading' (duration: 871.651277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:45.435045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.562808Z","time spent":"872.217661ms","remote":"127.0.0.1:57416","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":"warn","ts":"2026-04-16T06:38:45.436484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.541869Z","time spent":"890.95908ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T06:38:45.436593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.55532Z","time spent":"878.863255ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:38:45.436624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.541004Z","time spent":"893.203293ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:38:45.437196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.539736Z","time spent":"893.041498ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:38:45.437329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.550691Z","time spent":"882.310313ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T06:38:45.437392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.577898Z","time spent":"856.605851ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T06:38:45.438149Z","caller":"traceutil/trace.go:171","msg":"trace[277123813] transaction","detail":"{read_only:false; number_of_response:0; response_revision:480; }","duration":"854.680316ms","start":"2026-04-16T06:38:44.583456Z","end":"2026-04-16T06:38:45.438136Z","steps":["trace[277123813] 'process raft request' (duration: 854.422189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:45.438214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.58344Z","time spent":"854.741617ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T06:38:45.438476Z","caller":"traceutil/trace.go:171","msg":"trace[1962076691] transaction","detail":"{read_only:false; number_of_response:0; response_revision:480; }","duration":"854.942763ms","start":"2026-04-16T06:38:44.583517Z","end":"2026-04-16T06:38:45.43846Z","steps":["trace[1962076691] 'process raft request' (duration: 854.539892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:45.438522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.583512Z","time spent":"854.990165ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T06:38:45.439092Z","caller":"traceutil/trace.go:171","msg":"trace[612657688] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"848.358581ms","start":"2026-04-16T06:38:44.590575Z","end":"2026-04-16T06:38:45.438934Z","steps":["trace[612657688] 'process raft request' (duration: 847.512358ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:45.439572Z","caller":"traceutil/trace.go:171","msg":"trace[728091678] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"808.177149ms","start":"2026-04-16T06:38:44.631372Z","end":"2026-04-16T06:38:45.439549Z","steps":["trace[728091678] 'process raft request' (duration: 807.49556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:45.439145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.590555Z","time spent":"848.571277ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T06:38:45.439729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.631352Z","time spent":"808.314663ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:38:45.444133Z","caller":"traceutil/trace.go:171","msg":"trace[1063021171] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"841.17198ms","start":"2026-04-16T06:38:44.602937Z","end":"2026-04-16T06:38:45.444109Z","steps":["trace[1063021171] 'process raft request' (duration: 835.788073ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:45.444183Z","caller":"traceutil/trace.go:171","msg":"trace[2043230345] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"723.70121ms","start":"2026-04-16T06:38:44.720457Z","end":"2026-04-16T06:38:45.444158Z","steps":["trace[2043230345] 'process raft request' (duration: 719.242738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:45.444256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.602918Z","time spent":"841.271864ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:38:45.444345Z","caller":"traceutil/trace.go:171","msg":"trace[462390958] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"853.648756ms","start":"2026-04-16T06:38:44.590689Z","end":"2026-04-16T06:38:45.444337Z","steps":["trace[462390958] 'process raft request' (duration: 847.483567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:45.444387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.590652Z","time spent":"853.713048ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:38:45.444439Z","caller":"traceutil/trace.go:171","msg":"trace[475329428] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"841.180862ms","start":"2026-04-16T06:38:44.603251Z","end":"2026-04-16T06:38:45.444432Z","steps":["trace[475329428] 'process raft request' (duration: 835.560028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:45.444453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.720431Z","time spent":"723.804344ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T06:38:45.444477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.603244Z","time spent":"841.213252ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:38:45.444723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.653005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-16T06:38:45.444766Z","caller":"traceutil/trace.go:171","msg":"trace[279210410] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:486; }","duration":"472.719916ms","start":"2026-04-16T06:38:44.972038Z","end":"2026-04-16T06:38:45.444758Z","steps":["trace[279210410] 'agreement among raft nodes before linearized reading' (duration: 472.569062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:45.444784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.972023Z","time spent":"472.757838ms","remote":"127.0.0.1:57294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-16T06:38:45.445219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.035562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:38:45.445251Z","caller":"traceutil/trace.go:171","msg":"trace[47165837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"663.067683ms","start":"2026-04-16T06:38:44.782175Z","end":"2026-04-16T06:38:45.445242Z","steps":["trace[47165837] 'agreement among raft nodes before linearized reading' (duration: 663.001241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:45.445319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:44.782125Z","time spent":"663.188606ms","remote":"127.0.0.1:57178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T06:38:46.257988Z","caller":"traceutil/trace.go:171","msg":"trace[544008381] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"234.453337ms","start":"2026-04-16T06:38:46.023515Z","end":"2026-04-16T06:38:46.257969Z","steps":["trace[544008381] 'process raft request' (duration: 234.214611ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:46.26865Z","caller":"traceutil/trace.go:171","msg":"trace[1079835278] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"237.314165ms","start":"2026-04-16T06:38:46.031318Z","end":"2026-04-16T06:38:46.268632Z","steps":["trace[1079835278] 'process raft request' (duration: 236.640257ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:46.268766Z","caller":"traceutil/trace.go:171","msg":"trace[305839913] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"126.294964ms","start":"2026-04-16T06:38:46.142466Z","end":"2026-04-16T06:38:46.268761Z","steps":["trace[305839913] 'process raft request' (duration: 125.612866ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:46.692107Z","caller":"traceutil/trace.go:171","msg":"trace[1987474422] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"284.831048ms","start":"2026-04-16T06:38:46.407259Z","end":"2026-04-16T06:38:46.69209Z","steps":["trace[1987474422] 'process raft request' (duration: 284.398136ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:46.699872Z","caller":"traceutil/trace.go:171","msg":"trace[1072741864] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"278.895636ms","start":"2026-04-16T06:38:46.42096Z","end":"2026-04-16T06:38:46.699855Z","steps":["trace[1072741864] 'process raft request' (duration: 278.341171ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:46.70059Z","caller":"traceutil/trace.go:171","msg":"trace[974499916] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"187.307995ms","start":"2026-04-16T06:38:46.513258Z","end":"2026-04-16T06:38:46.700566Z","steps":["trace[974499916] 'read index received' (duration: 178.979328ms)","trace[974499916] 'applied index is now lower than readState.Index' (duration: 8.327687ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:38:46.700699Z","caller":"traceutil/trace.go:171","msg":"trace[292751125] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"231.98786ms","start":"2026-04-16T06:38:46.4687Z","end":"2026-04-16T06:38:46.700688Z","steps":["trace[292751125] 'process raft request' (duration: 231.204359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:46.700887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.985962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:38:46.701903Z","caller":"traceutil/trace.go:171","msg":"trace[858529026] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:556; }","duration":"161.050531ms","start":"2026-04-16T06:38:46.540835Z","end":"2026-04-16T06:38:46.701885Z","steps":["trace[858529026] 'agreement among raft nodes before linearized reading' (duration: 159.989431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:46.700914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.653344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T06:38:46.702385Z","caller":"traceutil/trace.go:171","msg":"trace[1286197733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"189.160396ms","start":"2026-04-16T06:38:46.513211Z","end":"2026-04-16T06:38:46.702371Z","steps":["trace[1286197733] 'agreement among raft nodes before linearized reading' (duration: 187.619633ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:48.05117Z","caller":"traceutil/trace.go:171","msg":"trace[249540155] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"111.317363ms","start":"2026-04-16T06:38:47.939833Z","end":"2026-04-16T06:38:48.05115Z","steps":["trace[249540155] 'read index received' (duration: 111.161679ms)","trace[249540155] 'applied index is now lower than readState.Index' (duration: 154.984µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:38:48.051405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.55296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-04-16T06:38:48.051435Z","caller":"traceutil/trace.go:171","msg":"trace[1166235006] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:576; }","duration":"111.624431ms","start":"2026-04-16T06:38:47.939801Z","end":"2026-04-16T06:38:48.051426Z","steps":["trace[1166235006] 'agreement among raft nodes before linearized reading' (duration: 111.431315ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:48.051561Z","caller":"traceutil/trace.go:171","msg":"trace[1086065228] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"140.090048ms","start":"2026-04-16T06:38:47.911458Z","end":"2026-04-16T06:38:48.051548Z","steps":["trace[1086065228] 'process raft request' (duration: 139.584675ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:50.48485Z","caller":"traceutil/trace.go:171","msg":"trace[37133965] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"169.100639ms","start":"2026-04-16T06:38:50.315721Z","end":"2026-04-16T06:38:50.484822Z","steps":["trace[37133965] 'read index received' (duration: 165.153576ms)","trace[37133965] 'applied index is now lower than readState.Index' (duration: 3.946013ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:38:50.484945Z","caller":"traceutil/trace.go:171","msg":"trace[1348694051] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"189.679751ms","start":"2026-04-16T06:38:50.295227Z","end":"2026-04-16T06:38:50.484907Z","steps":["trace[1348694051] 'process raft request' (duration: 186.806705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:50.487066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.327198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T06:38:50.489664Z","caller":"traceutil/trace.go:171","msg":"trace[1797131084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"173.960687ms","start":"2026-04-16T06:38:50.315676Z","end":"2026-04-16T06:38:50.489637Z","steps":["trace[1797131084] 'agreement among raft nodes before linearized reading' (duration: 169.329136ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:53.06081Z","caller":"traceutil/trace.go:171","msg":"trace[466346281] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"325.147238ms","start":"2026-04-16T06:38:52.735638Z","end":"2026-04-16T06:38:53.060786Z","steps":["trace[466346281] 'process raft request' (duration: 325.018235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:53.060961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:52.735603Z","time spent":"325.286972ms","remote":"127.0.0.1:57416","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-04-16T06:38:54.573083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367044569970231,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:38:54.859404Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.106664802s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-16T06:38:55.319021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.034406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:38:55.319152Z","caller":"traceutil/trace.go:171","msg":"trace[899950110] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.566486427s","start":"2026-04-16T06:38:53.752642Z","end":"2026-04-16T06:38:55.319128Z","steps":["trace[899950110] 'process raft request' (duration: 1.106974259s)","trace[899950110] 'compare' (duration: 458.79785ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:38:55.319242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:53.752616Z","time spent":"1.56658289s","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:38:55.344243Z","caller":"traceutil/trace.go:171","msg":"trace[890243074] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"1.271580936s","start":"2026-04-16T06:38:54.072643Z","end":"2026-04-16T06:38:55.344224Z","steps":["trace[890243074] 'read index received' (duration: 786.996674ms)","trace[890243074] 'applied index is now lower than readState.Index' (duration: 484.583392ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:38:55.344371Z","caller":"traceutil/trace.go:171","msg":"trace[1280428605] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.201922241s","start":"2026-04-16T06:38:54.14242Z","end":"2026-04-16T06:38:55.344342Z","steps":["trace[1280428605] 'process raft request' (duration: 1.201703725s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:55.344503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:54.142383Z","time spent":"1.202048044s","remote":"127.0.0.1:57356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4791,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:38:55.34454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.720672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-16T06:38:55.344605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.709868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:38:55.344668Z","caller":"traceutil/trace.go:171","msg":"trace[2124940186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"698.804679ms","start":"2026-04-16T06:38:54.645846Z","end":"2026-04-16T06:38:55.34465Z","steps":["trace[2124940186] 'agreement among raft nodes before linearized reading' (duration: 698.715667ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:38:55.344623Z","caller":"traceutil/trace.go:171","msg":"trace[1572658076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"810.852205ms","start":"2026-04-16T06:38:54.533755Z","end":"2026-04-16T06:38:55.344608Z","steps":["trace[1572658076] 'agreement among raft nodes before linearized reading' (duration: 810.64124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:55.344718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:54.645821Z","time spent":"698.886082ms","remote":"127.0.0.1:57178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T06:38:55.344748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:54.533736Z","time spent":"810.999058ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T06:38:55.344556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.940032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T06:38:55.344908Z","caller":"traceutil/trace.go:171","msg":"trace[243184572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"279.333812ms","start":"2026-04-16T06:38:55.065556Z","end":"2026-04-16T06:38:55.344889Z","steps":["trace[243184572] 'agreement among raft nodes before linearized reading' (duration: 278.866101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:55.344533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271897224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-16T06:38:55.345086Z","caller":"traceutil/trace.go:171","msg":"trace[691453662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"1.272481349s","start":"2026-04-16T06:38:54.072589Z","end":"2026-04-16T06:38:55.345071Z","steps":["trace[691453662] 'agreement among raft nodes before linearized reading' (duration: 1.271763151s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:38:55.345138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:38:54.072569Z","time spent":"1.272555251s","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T06:39:10.809388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.028353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hhffn\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-16T06:39:10.809505Z","caller":"traceutil/trace.go:171","msg":"trace[640148461] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hhffn; range_end:; response_count:1; response_revision:742; }","duration":"168.181856ms","start":"2026-04-16T06:39:10.64129Z","end":"2026-04-16T06:39:10.809472Z","steps":["trace[640148461] 'range keys from in-memory index tree' (duration: 167.836118ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:39:34.056163Z","caller":"traceutil/trace.go:171","msg":"trace[186935542] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"164.649662ms","start":"2026-04-16T06:39:33.891484Z","end":"2026-04-16T06:39:34.056133Z","steps":["trace[186935542] 'process raft request' (duration: 164.475399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:18.303547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367044569971245,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T06:40:18.335484Z","caller":"traceutil/trace.go:171","msg":"trace[1304628273] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"532.715332ms","start":"2026-04-16T06:40:17.802755Z","end":"2026-04-16T06:40:18.33547Z","steps":["trace[1304628273] 'read index received' (duration: 532.550589ms)","trace[1304628273] 'applied index is now lower than readState.Index' (duration: 164.123µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:40:18.335725Z","caller":"traceutil/trace.go:171","msg":"trace[2084153349] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"591.374456ms","start":"2026-04-16T06:40:17.744341Z","end":"2026-04-16T06:40:18.335716Z","steps":["trace[2084153349] 'process raft request' (duration: 591.004201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:18.335842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:17.744321Z","time spent":"591.458696ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:40:18.336024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.215006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T06:40:18.336058Z","caller":"traceutil/trace.go:171","msg":"trace[560712590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1060; }","duration":"196.274377ms","start":"2026-04-16T06:40:18.139773Z","end":"2026-04-16T06:40:18.336047Z","steps":["trace[560712590] 'agreement among raft nodes before linearized reading' (duration: 196.173766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:18.336073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.251967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T06:40:18.336129Z","caller":"traceutil/trace.go:171","msg":"trace[1664068090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1060; }","duration":"525.306648ms","start":"2026-04-16T06:40:17.810803Z","end":"2026-04-16T06:40:18.33611Z","steps":["trace[1664068090] 'agreement among raft nodes before linearized reading' (duration: 525.167536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:18.336167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:17.810799Z","time spent":"525.360399ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T06:40:18.336152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.39532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-16T06:40:18.336055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.421718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T06:40:18.336216Z","caller":"traceutil/trace.go:171","msg":"trace[565962872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1060; }","duration":"533.471601ms","start":"2026-04-16T06:40:17.802729Z","end":"2026-04-16T06:40:18.3362Z","steps":["trace[565962872] 'agreement among raft nodes before linearized reading' (duration: 533.262468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:18.336246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:17.802659Z","time spent":"533.581213ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T06:40:18.33627Z","caller":"traceutil/trace.go:171","msg":"trace[278041677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1060; }","duration":"525.657893ms","start":"2026-04-16T06:40:17.810576Z","end":"2026-04-16T06:40:18.336234Z","steps":["trace[278041677] 'agreement among raft nodes before linearized reading' (duration: 525.323208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:18.336367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:17.810562Z","time spent":"525.777274ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T06:40:19.426567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.903471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:40:19.426663Z","caller":"traceutil/trace.go:171","msg":"trace[2141193188] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"1.086804334s","start":"2026-04-16T06:40:18.33984Z","end":"2026-04-16T06:40:19.426644Z","steps":["trace[2141193188] 'process raft request' (duration: 573.751511ms)","trace[2141193188] 'compare' (duration: 512.75148ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:40:19.426709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:18.339807Z","time spent":"1.086882025s","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:40:19.435357Z","caller":"traceutil/trace.go:171","msg":"trace[1445296029] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"1.095163281s","start":"2026-04-16T06:40:18.340162Z","end":"2026-04-16T06:40:19.435326Z","steps":["trace[1445296029] 'process raft request' (duration: 1.094975188s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:19.435549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:18.340145Z","time spent":"1.095297212s","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:40:19.436138Z","caller":"traceutil/trace.go:171","msg":"trace[1327355115] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"1.095926271s","start":"2026-04-16T06:40:18.340183Z","end":"2026-04-16T06:40:19.436109Z","steps":["trace[1327355115] 'process raft request' (duration: 1.09509057s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:19.436257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:18.340163Z","time spent":"1.096031012s","remote":"127.0.0.1:57416","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-04-16T06:40:19.436593Z","caller":"traceutil/trace.go:171","msg":"trace[1133753666] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1099; }","duration":"790.949034ms","start":"2026-04-16T06:40:18.645634Z","end":"2026-04-16T06:40:19.436583Z","steps":["trace[1133753666] 'read index received' (duration: 267.969746ms)","trace[1133753666] 'applied index is now lower than readState.Index' (duration: 522.978548ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:40:19.436653Z","caller":"traceutil/trace.go:171","msg":"trace[929946630] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"1.096351386s","start":"2026-04-16T06:40:18.34029Z","end":"2026-04-16T06:40:19.436641Z","steps":["trace[929946630] 'process raft request' (duration: 1.095779268s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:19.43671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:18.340281Z","time spent":"1.096402156s","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:40:19.436744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.121157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:40:19.436771Z","caller":"traceutil/trace.go:171","msg":"trace[1434759809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"791.191528ms","start":"2026-04-16T06:40:18.645572Z","end":"2026-04-16T06:40:19.436763Z","steps":["trace[1434759809] 'agreement among raft nodes before linearized reading' (duration: 791.127357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:19.436789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:18.645548Z","time spent":"791.237058ms","remote":"127.0.0.1:57178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T06:40:19.436663Z","caller":"traceutil/trace.go:171","msg":"trace[887289638] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"1.096339226s","start":"2026-04-16T06:40:18.340316Z","end":"2026-04-16T06:40:19.436655Z","steps":["trace[887289638] 'process raft request' (duration: 1.096235194s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:19.436841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.919833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:40:19.43706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:18.340304Z","time spent":"1.09673017s","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:40:19.437065Z","caller":"traceutil/trace.go:171","msg":"trace[1992592411] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1065; }","duration":"701.236218ms","start":"2026-04-16T06:40:18.735809Z","end":"2026-04-16T06:40:19.437045Z","steps":["trace[1992592411] 'agreement among raft nodes before linearized reading' (duration: 700.918355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:19.437112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:18.735793Z","time spent":"701.306169ms","remote":"127.0.0.1:41434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-04-16T06:40:42.754086Z","caller":"traceutil/trace.go:171","msg":"trace[1196462366] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1182; }","duration":"106.872315ms","start":"2026-04-16T06:40:42.647191Z","end":"2026-04-16T06:40:42.754063Z","steps":["trace[1196462366] 'read index received' (duration: 106.702933ms)","trace[1196462366] 'applied index is now lower than readState.Index' (duration: 168.512µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:40:42.754205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.000417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:40:42.754315Z","caller":"traceutil/trace.go:171","msg":"trace[1622693045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1139; }","duration":"107.163629ms","start":"2026-04-16T06:40:42.64714Z","end":"2026-04-16T06:40:42.754304Z","steps":["trace[1622693045] 'agreement among raft nodes before linearized reading' (duration: 107.015077ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:40:42.754313Z","caller":"traceutil/trace.go:171","msg":"trace[1280075275] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"338.41002ms","start":"2026-04-16T06:40:42.415887Z","end":"2026-04-16T06:40:42.754297Z","steps":["trace[1280075275] 'process raft request' (duration: 338.052206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:42.754411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:42.415829Z","time spent":"338.520463ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:40:43.776482Z","caller":"traceutil/trace.go:171","msg":"trace[4504101] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"147.821854ms","start":"2026-04-16T06:40:43.628636Z","end":"2026-04-16T06:40:43.776458Z","steps":["trace[4504101] 'read index received' (duration: 147.652552ms)","trace[4504101] 'applied index is now lower than readState.Index' (duration: 168.352µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:40:43.776556Z","caller":"traceutil/trace.go:171","msg":"trace[859204794] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"151.111555ms","start":"2026-04-16T06:40:43.625411Z","end":"2026-04-16T06:40:43.776523Z","steps":["trace[859204794] 'process raft request' (duration: 150.909092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:43.776924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.985193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-16T06:40:43.776898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.148758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-16T06:40:43.776954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.733479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T06:40:43.777003Z","caller":"traceutil/trace.go:171","msg":"trace[925110989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1141; }","duration":"148.356361ms","start":"2026-04-16T06:40:43.628628Z","end":"2026-04-16T06:40:43.776985Z","steps":["trace[925110989] 'agreement among raft nodes before linearized reading' (duration: 148.122398ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:40:43.777011Z","caller":"traceutil/trace.go:171","msg":"trace[782953830] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1141; }","duration":"142.939552ms","start":"2026-04-16T06:40:43.634059Z","end":"2026-04-16T06:40:43.776999Z","steps":["trace[782953830] 'agreement among raft nodes before linearized reading' (duration: 142.717919ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:40:43.777022Z","caller":"traceutil/trace.go:171","msg":"trace[411202641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1141; }","duration":"128.085973ms","start":"2026-04-16T06:40:43.648886Z","end":"2026-04-16T06:40:43.776972Z","steps":["trace[411202641] 'agreement among raft nodes before linearized reading' (duration: 127.883761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:43.77725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.610004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-16T06:40:43.777314Z","caller":"traceutil/trace.go:171","msg":"trace[2051682530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1141; }","duration":"148.717115ms","start":"2026-04-16T06:40:43.628582Z","end":"2026-04-16T06:40:43.777299Z","steps":["trace[2051682530] 'agreement among raft nodes before linearized reading' (duration: 147.986506ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:40:44.150013Z","caller":"traceutil/trace.go:171","msg":"trace[1951239204] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"369.413933ms","start":"2026-04-16T06:40:43.780553Z","end":"2026-04-16T06:40:44.149967Z","steps":["trace[1951239204] 'process raft request' (duration: 271.778546ms)","trace[1951239204] 'compare' (duration: 97.517666ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:40:44.150198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:43.780536Z","time spent":"369.575785ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:40:44.171782Z","caller":"traceutil/trace.go:171","msg":"trace[1036684423] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"390.348507ms","start":"2026-04-16T06:40:43.78142Z","end":"2026-04-16T06:40:44.171769Z","steps":["trace[1036684423] 'process raft request' (duration: 390.209726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:44.171859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:43.781393Z","time spent":"390.424369ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:40:44.171958Z","caller":"traceutil/trace.go:171","msg":"trace[1346173386] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"389.580868ms","start":"2026-04-16T06:40:43.78236Z","end":"2026-04-16T06:40:44.171941Z","steps":["trace[1346173386] 'process raft request' (duration: 389.381746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:44.172038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:43.782349Z","time spent":"389.648159ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:40:44.172131Z","caller":"traceutil/trace.go:171","msg":"trace[616743593] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1185; }","duration":"374.555268ms","start":"2026-04-16T06:40:43.797542Z","end":"2026-04-16T06:40:44.172097Z","steps":["trace[616743593] 'read index received' (duration: 254.80619ms)","trace[616743593] 'applied index is now lower than readState.Index' (duration: 119.747888ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:40:44.172252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.69492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:40:44.172298Z","caller":"traceutil/trace.go:171","msg":"trace[1871720991] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1145; }","duration":"374.77555ms","start":"2026-04-16T06:40:43.79751Z","end":"2026-04-16T06:40:44.172286Z","steps":["trace[1871720991] 'agreement among raft nodes before linearized reading' (duration: 374.665329ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:40:44.172305Z","caller":"traceutil/trace.go:171","msg":"trace[433267168] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"389.763231ms","start":"2026-04-16T06:40:43.782525Z","end":"2026-04-16T06:40:44.172288Z","steps":["trace[433267168] 'process raft request' (duration: 389.351506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:40:44.172336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:43.797493Z","time spent":"374.832592ms","remote":"127.0.0.1:57700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:40:44.172375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:40:43.782518Z","time spent":"389.820532ms","remote":"127.0.0.1:57416","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-04-16T06:41:32.854657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367044569971880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:41:33.355119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367044569971880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:41:33.443627Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.213373752s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T06:41:33.443963Z","caller":"traceutil/trace.go:171","msg":"trace[1762862769] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"1.213832115s","start":"2026-04-16T06:41:32.230111Z","end":"2026-04-16T06:41:33.443943Z","steps":["trace[1762862769] 'process raft request' (duration: 1.213715682s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:41:33.444072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:41:32.230066Z","time spent":"1.213940448s","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:41:33.487655Z","caller":"traceutil/trace.go:171","msg":"trace[998417506] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1352; }","duration":"1.133046623s","start":"2026-04-16T06:41:32.354584Z","end":"2026-04-16T06:41:33.48763Z","steps":["trace[998417506] 'read index received' (duration: 1.089290826s)","trace[998417506] 'applied index is now lower than readState.Index' (duration: 43.754747ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:41:33.487962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.547205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:41:33.487976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117116356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T06:41:33.487959Z","caller":"traceutil/trace.go:171","msg":"trace[232868826] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"542.155101ms","start":"2026-04-16T06:41:32.945761Z","end":"2026-04-16T06:41:33.487916Z","steps":["trace[232868826] 'process raft request' (duration: 541.734109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:41:33.488031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097027268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T06:41:33.488035Z","caller":"traceutil/trace.go:171","msg":"trace[896296312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1300; }","duration":"1.117199548s","start":"2026-04-16T06:41:32.370819Z","end":"2026-04-16T06:41:33.488018Z","steps":["trace[896296312] 'agreement among raft nodes before linearized reading' (duration: 1.117037054s)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:41:33.488081Z","caller":"traceutil/trace.go:171","msg":"trace[211083795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1300; }","duration":"1.097104831s","start":"2026-04-16T06:41:32.390962Z","end":"2026-04-16T06:41:33.488066Z","steps":["trace[211083795] 'agreement among raft nodes before linearized reading' (duration: 1.096971657s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:41:33.488094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:41:32.370808Z","time spent":"1.11727244s","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T06:41:33.488119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:41:32.390947Z","time spent":"1.097162182s","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T06:41:33.488115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.481162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:41:33.488171Z","caller":"traceutil/trace.go:171","msg":"trace[756309982] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1300; }","duration":"637.585074ms","start":"2026-04-16T06:41:32.850575Z","end":"2026-04-16T06:41:33.48816Z","steps":["trace[756309982] 'agreement among raft nodes before linearized reading' (duration: 637.494532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:41:33.488051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.035327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T06:41:33.488209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:41:32.850552Z","time spent":"637.647696ms","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-04-16T06:41:33.488228Z","caller":"traceutil/trace.go:171","msg":"trace[1655381066] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1300; }","duration":"321.241083ms","start":"2026-04-16T06:41:33.166969Z","end":"2026-04-16T06:41:33.48821Z","steps":["trace[1655381066] 'agreement among raft nodes before linearized reading' (duration: 321.055657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:41:33.488211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097244144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-16T06:41:33.488277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:41:33.16695Z","time spent":"321.311544ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-04-16T06:41:33.488006Z","caller":"traceutil/trace.go:171","msg":"trace[426995318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"841.645689ms","start":"2026-04-16T06:41:32.646345Z","end":"2026-04-16T06:41:33.487991Z","steps":["trace[426995318] 'agreement among raft nodes before linearized reading' (duration: 841.565696ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:41:33.488292Z","caller":"traceutil/trace.go:171","msg":"trace[592406821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1300; }","duration":"1.097341886s","start":"2026-04-16T06:41:32.390931Z","end":"2026-04-16T06:41:33.488273Z","steps":["trace[592406821] 'agreement among raft nodes before linearized reading' (duration: 1.097057858s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:41:33.48834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:41:32.39087Z","time spent":"1.09746191s","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T06:41:33.488345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:41:32.646319Z","time spent":"842.008208ms","remote":"127.0.0.1:57178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T06:41:33.48826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:41:32.945739Z","time spent":"542.311796ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:41:33.488631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134048592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T06:41:33.488716Z","caller":"traceutil/trace.go:171","msg":"trace[849095843] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1300; }","duration":"1.134152075s","start":"2026-04-16T06:41:32.354545Z","end":"2026-04-16T06:41:33.488697Z","steps":["trace[849095843] 'agreement among raft nodes before linearized reading' (duration: 1.133205208s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:41:33.488746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:41:32.354523Z","time spent":"1.134210827s","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T06:44:46.473512Z","caller":"traceutil/trace.go:171","msg":"trace[443891720] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"141.364258ms","start":"2026-04-16T06:44:46.33212Z","end":"2026-04-16T06:44:46.473484Z","steps":["trace[443891720] 'process raft request' (duration: 140.841944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:44:46.711027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.762713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:44:46.711303Z","caller":"traceutil/trace.go:171","msg":"trace[1148696648] linearizableReadLoop","detail":"{readStateIndex:2031; appliedIndex:2027; }","duration":"217.512244ms","start":"2026-04-16T06:44:46.493772Z","end":"2026-04-16T06:44:46.711284Z","steps":["trace[1148696648] 'read index received' (duration: 93.414393ms)","trace[1148696648] 'applied index is now lower than readState.Index' (duration: 124.096881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:44:46.71143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.651978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:44:46.711458Z","caller":"traceutil/trace.go:171","msg":"trace[946307061] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1939; }","duration":"217.70764ms","start":"2026-04-16T06:44:46.49374Z","end":"2026-04-16T06:44:46.711448Z","steps":["trace[946307061] 'agreement among raft nodes before linearized reading' (duration: 217.595117ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:44:46.711487Z","caller":"traceutil/trace.go:171","msg":"trace[1153229708] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"233.331353ms","start":"2026-04-16T06:44:46.478103Z","end":"2026-04-16T06:44:46.711434Z","steps":["trace[1153229708] 'process raft request' (duration: 233.020764ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:44:46.71158Z","caller":"traceutil/trace.go:171","msg":"trace[533576713] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"233.401605ms","start":"2026-04-16T06:44:46.478111Z","end":"2026-04-16T06:44:46.711513Z","steps":["trace[533576713] 'process raft request' (duration: 233.102506ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:44:46.711676Z","caller":"traceutil/trace.go:171","msg":"trace[630242253] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"233.515698ms","start":"2026-04-16T06:44:46.478149Z","end":"2026-04-16T06:44:46.711664Z","steps":["trace[630242253] 'process raft request' (duration: 233.099006ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:44:46.71193Z","caller":"traceutil/trace.go:171","msg":"trace[1023481683] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"234.812582ms","start":"2026-04-16T06:44:46.477099Z","end":"2026-04-16T06:44:46.711912Z","steps":["trace[1023481683] 'process raft request' (duration: 110.066083ms)","trace[1023481683] 'compare' (duration: 123.269729ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:45:10.43298Z","caller":"traceutil/trace.go:171","msg":"trace[1504120961] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"197.948997ms","start":"2026-04-16T06:45:10.235003Z","end":"2026-04-16T06:45:10.432952Z","steps":["trace[1504120961] 'process raft request' (duration: 197.774473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:45:10.736974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.165281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:45:10.737052Z","caller":"traceutil/trace.go:171","msg":"trace[1847020983] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2013; }","duration":"175.310554ms","start":"2026-04-16T06:45:10.561724Z","end":"2026-04-16T06:45:10.737034Z","steps":["trace[1847020983] 'count revisions from in-memory index tree' (duration: 174.995956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:45:10.737057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.837483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T06:45:10.737116Z","caller":"traceutil/trace.go:171","msg":"trace[1423109617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2013; }","duration":"137.958226ms","start":"2026-04-16T06:45:10.599145Z","end":"2026-04-16T06:45:10.737103Z","steps":["trace[1423109617] 'range keys from in-memory index tree' (duration: 137.689828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:45:13.115665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.729086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:45:13.116028Z","caller":"traceutil/trace.go:171","msg":"trace[975938314] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"279.592781ms","start":"2026-04-16T06:45:12.836404Z","end":"2026-04-16T06:45:13.115997Z","steps":["trace[975938314] 'process raft request' (duration: 279.389046ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:45:13.116045Z","caller":"traceutil/trace.go:171","msg":"trace[2139821503] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"287.886875ms","start":"2026-04-16T06:45:12.82812Z","end":"2026-04-16T06:45:13.116007Z","steps":["trace[2139821503] 'process raft request' (duration: 34.711308ms)","trace[2139821503] 'compare' (duration: 252.546081ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:45:13.116286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.912797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T06:45:13.116354Z","caller":"traceutil/trace.go:171","msg":"trace[928976366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2023; }","duration":"258.01422ms","start":"2026-04-16T06:45:12.858324Z","end":"2026-04-16T06:45:13.116339Z","steps":["trace[928976366] 'agreement among raft nodes before linearized reading' (duration: 257.803274ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:45:13.116092Z","caller":"traceutil/trace.go:171","msg":"trace[1852465343] linearizableReadLoop","detail":"{readStateIndex:2121; appliedIndex:2119; }","duration":"257.548666ms","start":"2026-04-16T06:45:12.858373Z","end":"2026-04-16T06:45:13.115922Z","steps":["trace[1852465343] 'read index received' (duration: 4.458201ms)","trace[1852465343] 'applied index is now lower than readState.Index' (duration: 253.089375ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:45:13.116862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.894053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T06:45:13.116909Z","caller":"traceutil/trace.go:171","msg":"trace[523940241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2023; }","duration":"228.963264ms","start":"2026-04-16T06:45:12.887929Z","end":"2026-04-16T06:45:13.116893Z","steps":["trace[523940241] 'agreement among raft nodes before linearized reading' (duration: 228.853821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:45:13.116982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.726988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-16T06:45:13.117031Z","caller":"traceutil/trace.go:171","msg":"trace[1581695971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2023; }","duration":"248.820241ms","start":"2026-04-16T06:45:12.868195Z","end":"2026-04-16T06:45:13.117015Z","steps":["trace[1581695971] 'agreement among raft nodes before linearized reading' (duration: 248.543163ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:45:43.454486Z","caller":"traceutil/trace.go:171","msg":"trace[1719563304] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"143.789723ms","start":"2026-04-16T06:45:43.310665Z","end":"2026-04-16T06:45:43.454455Z","steps":["trace[1719563304] 'process raft request' (duration: 143.463744ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:45:43.545438Z","caller":"traceutil/trace.go:171","msg":"trace[526465402] linearizableReadLoop","detail":"{readStateIndex:2320; appliedIndex:2318; }","duration":"230.124188ms","start":"2026-04-16T06:45:43.315279Z","end":"2026-04-16T06:45:43.545403Z","steps":["trace[526465402] 'read index received' (duration: 138.911282ms)","trace[526465402] 'applied index is now lower than readState.Index' (duration: 91.211436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:45:43.546293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.981471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-16T06:45:43.546466Z","caller":"traceutil/trace.go:171","msg":"trace[195933842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2214; }","duration":"231.180677ms","start":"2026-04-16T06:45:43.315246Z","end":"2026-04-16T06:45:43.546427Z","steps":["trace[195933842] 'agreement among raft nodes before linearized reading' (duration: 230.335713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:45:43.546745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.144185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T06:45:43.547038Z","caller":"traceutil/trace.go:171","msg":"trace[1529933798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2214; }","duration":"231.286378ms","start":"2026-04-16T06:45:43.315531Z","end":"2026-04-16T06:45:43.546817Z","steps":["trace[1529933798] 'agreement among raft nodes before linearized reading' (duration: 230.054774ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:45:43.547351Z","caller":"traceutil/trace.go:171","msg":"trace[73352215] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"234.360161ms","start":"2026-04-16T06:45:43.311198Z","end":"2026-04-16T06:45:43.545558Z","steps":["trace[73352215] 'process raft request' (duration: 180.167172ms)","trace[73352215] 'compare' (duration: 53.889651ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:45:44.275495Z","caller":"traceutil/trace.go:171","msg":"trace[1915038275] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"168.062486ms","start":"2026-04-16T06:45:44.107394Z","end":"2026-04-16T06:45:44.275456Z","steps":["trace[1915038275] 'process raft request' (duration: 167.890372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:45:45.737059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.202009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-16T06:45:45.737147Z","caller":"traceutil/trace.go:171","msg":"trace[2140778515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2228; }","duration":"116.349425ms","start":"2026-04-16T06:45:45.620775Z","end":"2026-04-16T06:45:45.737124Z","steps":["trace[2140778515] 'range keys from in-memory index tree' (duration: 115.942682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:45:45.73728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.788768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T06:45:45.737434Z","caller":"traceutil/trace.go:171","msg":"trace[43694460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2228; }","duration":"102.984543ms","start":"2026-04-16T06:45:45.634429Z","end":"2026-04-16T06:45:45.737414Z","steps":["trace[43694460] 'range keys from in-memory index tree' (duration: 102.591664ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:46:03.971525Z","caller":"traceutil/trace.go:171","msg":"trace[851005273] linearizableReadLoop","detail":"{readStateIndex:2463; appliedIndex:2462; }","duration":"112.88067ms","start":"2026-04-16T06:46:03.858626Z","end":"2026-04-16T06:46:03.971507Z","steps":["trace[851005273] 'read index received' (duration: 112.687805ms)","trace[851005273] 'applied index is now lower than readState.Index' (duration: 192.035µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:46:03.971593Z","caller":"traceutil/trace.go:171","msg":"trace[1846319794] transaction","detail":"{read_only:false; response_revision:2353; number_of_response:1; }","duration":"114.427681ms","start":"2026-04-16T06:46:03.857129Z","end":"2026-04-16T06:46:03.971557Z","steps":["trace[1846319794] 'process raft request' (duration: 114.237886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:46:03.97167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.024103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T06:46:03.971692Z","caller":"traceutil/trace.go:171","msg":"trace[2080592793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2353; }","duration":"113.090136ms","start":"2026-04-16T06:46:03.858596Z","end":"2026-04-16T06:46:03.971686Z","steps":["trace[2080592793] 'agreement among raft nodes before linearized reading' (duration: 112.979832ms)"],"step_count":1}