{"level":"warn","ts":"2026-04-01T09:58:12.419647Z","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-01T09:58:12.419974Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-01T09:58:12.420042Z","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-01T09:58:12.420057Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-04-01T09:58:12.420074Z","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-01T09:58:12.42071Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-04-01T09:58:12.420787Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-01T09:58:12.467043Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"44.578863ms"} {"level":"info","ts":"2026-04-01T09:58:12.536627Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-04-01T09:58:12.536729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T09:58:12.536751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-04-01T09:58:12.53676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T09:58:12.536772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-04-01T09:58:12.536807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-04-01T09:58:12.592044Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T09:58:12.598627Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T09:58:12.604818Z","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-01T09:58:12.616398Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T09:58:12.616647Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T09:58:12.616727Z","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-01T09:58:12.616851Z","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-01T09:58:12.61811Z","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-01T09:58:12.621002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-04-01T09:58:12.621129Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-04-01T09:58:12.623377Z","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-01T09:58:12.623462Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-04-01T09:58:12.623593Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-04-01T09:58:12.624723Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T09:58:12.624774Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T09:58:13.243236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T09:58:13.243426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T09:58:13.24353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-04-01T09:58:13.243622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-04-01T09:58:13.243698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-04-01T09:58:13.243795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-04-01T09:58:13.243903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-04-01T09:58:13.249159Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T09:58:13.249216Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T09:58:13.249287Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T09:58:13.249348Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T09:58:13.249517Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T09:58:13.249557Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T09:58:13.252045Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T09:58:13.252122Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T09:58:13.252209Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T09:58:13.252243Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T09:58:13.265163Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"warn","ts":"2026-04-01T09:58:18.12384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033207737s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T09:58:18.123964Z","caller":"traceutil/trace.go:171","msg":"trace[1702573914] linearizableReadLoop","detail":"{readStateIndex:215; appliedIndex:214; }","duration":"1.439720948s","start":"2026-04-01T09:58:16.684222Z","end":"2026-04-01T09:58:18.123943Z","steps":["trace[1702573914] 'read index received' (duration: 405.268752ms)","trace[1702573914] 'applied index is now lower than readState.Index' (duration: 1.034450706s)"],"step_count":2} {"level":"warn","ts":"2026-04-01T09:58:18.124115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439882588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-04-01T09:58:18.12417Z","caller":"traceutil/trace.go:171","msg":"trace[646175575] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:210; }","duration":"1.439977559s","start":"2026-04-01T09:58:16.684178Z","end":"2026-04-01T09:58:18.124156Z","steps":["trace[646175575] 'agreement among raft nodes before linearized reading' (duration: 1.439876638s)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:58:18.124119Z","caller":"traceutil/trace.go:171","msg":"trace[1598829498] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"1.69983682s","start":"2026-04-01T09:58:16.424218Z","end":"2026-04-01T09:58:18.124055Z","steps":["trace[1598829498] 'process raft request' (duration: 665.355455ms)","trace[1598829498] 'compare' (duration: 1.033090806s)"],"step_count":2} {"level":"warn","ts":"2026-04-01T09:58:18.124214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:58:16.684158Z","time spent":"1.440044789s","remote":"127.0.0.1:49488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-01T09:58:18.124304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.315064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-01T09:58:18.124369Z","caller":"traceutil/trace.go:171","msg":"trace[1568128053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:210; }","duration":"359.402954ms","start":"2026-04-01T09:58:17.764938Z","end":"2026-04-01T09:58:18.124341Z","steps":["trace[1568128053] 'agreement among raft nodes before linearized reading' (duration: 359.290313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:58:18.124363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:58:16.424188Z","time spent":"1.700036321s","remote":"127.0.0.1:49500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T09:58:18.124412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:58:17.764919Z","time spent":"359.485335ms","remote":"127.0.0.1:49568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T09:58:18.124389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.042885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T09:58:18.125156Z","caller":"traceutil/trace.go:171","msg":"trace[1880197052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:210; }","duration":"361.8623ms","start":"2026-04-01T09:58:17.763228Z","end":"2026-04-01T09:58:18.12509Z","steps":["trace[1880197052] 'agreement among raft nodes before linearized reading' (duration: 360.897305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:58:18.125332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:58:17.763175Z","time spent":"362.138102ms","remote":"127.0.0.1:49568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-01T09:58:18.592554Z","caller":"traceutil/trace.go:171","msg":"trace[1511075208] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"463.787648ms","start":"2026-04-01T09:58:18.12874Z","end":"2026-04-01T09:58:18.592528Z","steps":["trace[1511075208] 'process raft request' (duration: 458.638002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:58:18.592693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:58:18.128712Z","time spent":"463.91301ms","remote":"127.0.0.1:49568","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-01T09:58:18.593037Z","caller":"traceutil/trace.go:171","msg":"trace[1060085765] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"463.364488ms","start":"2026-04-01T09:58:18.129649Z","end":"2026-04-01T09:58:18.593013Z","steps":["trace[1060085765] 'process raft request' (duration: 463.190246ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:58:18.593085Z","caller":"traceutil/trace.go:171","msg":"trace[1393332353] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"462.050681ms","start":"2026-04-01T09:58:18.131023Z","end":"2026-04-01T09:58:18.593073Z","steps":["trace[1393332353] 'process raft request' (duration: 461.93768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:58:18.593147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:58:18.131003Z","time spent":"462.111901ms","remote":"127.0.0.1:49488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T09:58:18.593249Z","caller":"traceutil/trace.go:171","msg":"trace[6164128] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"463.401149ms","start":"2026-04-01T09:58:18.129822Z","end":"2026-04-01T09:58:18.593223Z","steps":["trace[6164128] 'process raft request' (duration: 463.098506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:58:18.593275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:58:18.129634Z","time spent":"463.470109ms","remote":"127.0.0.1:49568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T09:58:18.593389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:58:18.129811Z","time spent":"463.50616ms","remote":"127.0.0.1:49568","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-01T09:58:20.234255Z","caller":"traceutil/trace.go:171","msg":"trace[737439042] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"236.03277ms","start":"2026-04-01T09:58:19.998203Z","end":"2026-04-01T09:58:20.234236Z","steps":["trace[737439042] 'process raft request' (duration: 235.898714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:58:48.917262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.395244ms","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-01T09:58:48.917348Z","caller":"traceutil/trace.go:171","msg":"trace[71828565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:458; }","duration":"103.520138ms","start":"2026-04-01T09:58:48.813807Z","end":"2026-04-01T09:58:48.917327Z","steps":["trace[71828565] 'range keys from in-memory index tree' (duration: 103.28771ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:06.96392Z","caller":"traceutil/trace.go:171","msg":"trace[1716438867] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"228.599205ms","start":"2026-04-01T09:59:06.735296Z","end":"2026-04-01T09:59:06.963896Z","steps":["trace[1716438867] 'process raft request' (duration: 228.42737ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:07.173211Z","caller":"traceutil/trace.go:171","msg":"trace[1525826154] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"431.896766ms","start":"2026-04-01T09:59:06.741264Z","end":"2026-04-01T09:59:07.17316Z","steps":["trace[1525826154] 'process raft request' (duration: 431.788833ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:07.173291Z","caller":"traceutil/trace.go:171","msg":"trace[1515143103] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"418.202778ms","start":"2026-04-01T09:59:06.755056Z","end":"2026-04-01T09:59:07.173258Z","steps":["trace[1515143103] 'process raft request' (duration: 418.032583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:07.173446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:06.741242Z","time spent":"432.081581ms","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T09:59:07.173493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:06.755043Z","time spent":"418.362033ms","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T09:59:07.173633Z","caller":"traceutil/trace.go:171","msg":"trace[388332539] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"413.752785ms","start":"2026-04-01T09:59:06.759867Z","end":"2026-04-01T09:59:07.17362Z","steps":["trace[388332539] 'process raft request' (duration: 413.455397ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:07.173629Z","caller":"traceutil/trace.go:171","msg":"trace[745398363] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"415.40063ms","start":"2026-04-01T09:59:06.758212Z","end":"2026-04-01T09:59:07.173612Z","steps":["trace[745398363] 'process raft request' (duration: 415.060671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:07.173692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:06.759858Z","time spent":"413.797667ms","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T09:59:07.1737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:06.758192Z","time spent":"415.474963ms","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T09:59:07.173717Z","caller":"traceutil/trace.go:171","msg":"trace[1189362304] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"413.418106ms","start":"2026-04-01T09:59:06.760275Z","end":"2026-04-01T09:59:07.173693Z","steps":["trace[1189362304] 'process raft request' (duration: 413.153608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:07.173801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:06.760267Z","time spent":"413.492507ms","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T09:59:07.173811Z","caller":"traceutil/trace.go:171","msg":"trace[881561267] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"363.977302ms","start":"2026-04-01T09:59:06.809812Z","end":"2026-04-01T09:59:07.173789Z","steps":["trace[881561267] 'process raft request' (duration: 363.769647ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:07.173856Z","caller":"traceutil/trace.go:171","msg":"trace[1442170697] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"418.666881ms","start":"2026-04-01T09:59:06.755179Z","end":"2026-04-01T09:59:07.173846Z","steps":["trace[1442170697] 'process raft request' (duration: 418.061284ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:07.173727Z","caller":"traceutil/trace.go:171","msg":"trace[1472261336] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:582; }","duration":"347.874678ms","start":"2026-04-01T09:59:06.825828Z","end":"2026-04-01T09:59:07.173702Z","steps":["trace[1472261336] 'read index received' (duration: 137.972505ms)","trace[1472261336] 'applied index is now lower than readState.Index' (duration: 209.901363ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T09:59:07.173905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:06.809786Z","time spent":"364.096135ms","remote":"127.0.0.1:39552","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-01T09:59:07.173934Z","caller":"traceutil/trace.go:171","msg":"trace[870332783] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"265.458475ms","start":"2026-04-01T09:59:06.908465Z","end":"2026-04-01T09:59:07.173923Z","steps":["trace[870332783] 'process raft request' (duration: 265.179457ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:07.174061Z","caller":"traceutil/trace.go:171","msg":"trace[1700534904] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"432.850411ms","start":"2026-04-01T09:59:06.741195Z","end":"2026-04-01T09:59:07.174045Z","steps":["trace[1700534904] 'process raft request' (duration: 431.700169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:07.174131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:06.741176Z","time spent":"432.927574ms","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T09:59:07.173925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:06.755175Z","time spent":"418.716442ms","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T09:59:07.174221Z","caller":"traceutil/trace.go:171","msg":"trace[1076835964] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"390.546623ms","start":"2026-04-01T09:59:06.783658Z","end":"2026-04-01T09:59:07.174205Z","steps":["trace[1076835964] 'process raft request' (duration: 389.866105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:07.174267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.415942ms","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-01T09:59:07.174293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:06.783624Z","time spent":"390.637486ms","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T09:59:07.174325Z","caller":"traceutil/trace.go:171","msg":"trace[1159897804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"348.520935ms","start":"2026-04-01T09:59:06.825787Z","end":"2026-04-01T09:59:07.174308Z","steps":["trace[1159897804] 'agreement among raft nodes before linearized reading' (duration: 348.371751ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:07.173817Z","caller":"traceutil/trace.go:171","msg":"trace[411804862] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"418.698311ms","start":"2026-04-01T09:59:06.755101Z","end":"2026-04-01T09:59:07.173799Z","steps":["trace[411804862] 'process raft request' (duration: 418.092465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:07.174373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:06.825768Z","time spent":"348.592327ms","remote":"127.0.0.1:49568","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-01T09:59:07.174432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:06.755098Z","time spent":"419.285387ms","remote":"127.0.0.1:39552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T09:59:07.181067Z","caller":"traceutil/trace.go:171","msg":"trace[532526698] transaction","detail":"{read_only:false; number_of_response:1; response_revision:575; }","duration":"208.396819ms","start":"2026-04-01T09:59:06.972646Z","end":"2026-04-01T09:59:07.181043Z","steps":["trace[532526698] 'process raft request' (duration: 208.314916ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:07.181447Z","caller":"traceutil/trace.go:171","msg":"trace[282203888] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"210.843687ms","start":"2026-04-01T09:59:06.97059Z","end":"2026-04-01T09:59:07.181433Z","steps":["trace[282203888] 'process raft request' (duration: 210.100436ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:07.181624Z","caller":"traceutil/trace.go:171","msg":"trace[47141189] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"209.122319ms","start":"2026-04-01T09:59:06.972487Z","end":"2026-04-01T09:59:07.181609Z","steps":["trace[47141189] 'process raft request' (duration: 208.354917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:07.187817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.846072ms","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-01T09:59:07.187849Z","caller":"traceutil/trace.go:171","msg":"trace[584072246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"360.901933ms","start":"2026-04-01T09:59:06.82694Z","end":"2026-04-01T09:59:07.187842Z","steps":["trace[584072246] 'agreement among raft nodes before linearized reading' (duration: 360.826971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:07.18792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.314426ms","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-01T09:59:07.187939Z","caller":"traceutil/trace.go:171","msg":"trace[547509491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"191.357896ms","start":"2026-04-01T09:59:06.996577Z","end":"2026-04-01T09:59:07.187935Z","steps":["trace[547509491] 'agreement among raft nodes before linearized reading' (duration: 191.321315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:07.187866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:06.826931Z","time spent":"360.930863ms","remote":"127.0.0.1:49568","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-01T09:59:07.188077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.482677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T09:59:07.188135Z","caller":"traceutil/trace.go:171","msg":"trace[467756928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"345.60779ms","start":"2026-04-01T09:59:06.842514Z","end":"2026-04-01T09:59:07.188122Z","steps":["trace[467756928] 'agreement among raft nodes before linearized reading' (duration: 345.478877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:07.188176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:06.842491Z","time spent":"345.677263ms","remote":"127.0.0.1:49568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-01T09:59:13.746507Z","caller":"traceutil/trace.go:171","msg":"trace[1187749330] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"300.866768ms","start":"2026-04-01T09:59:13.445611Z","end":"2026-04-01T09:59:13.746477Z","steps":["trace[1187749330] 'process raft request' (duration: 300.307734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:13.746688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:13.445594Z","time spent":"301.011832ms","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T09:59:14.090184Z","caller":"traceutil/trace.go:171","msg":"trace[391312287] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"329.561366ms","start":"2026-04-01T09:59:13.760596Z","end":"2026-04-01T09:59:14.090157Z","steps":["trace[391312287] 'process raft request' (duration: 329.435633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:14.090338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:13.760575Z","time spent":"329.68482ms","remote":"127.0.0.1:49500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T09:59:14.425118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.187478ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T09:59:14.425286Z","caller":"traceutil/trace.go:171","msg":"trace[931404375] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:674; }","duration":"135.361382ms","start":"2026-04-01T09:59:14.2899Z","end":"2026-04-01T09:59:14.425261Z","steps":["trace[931404375] 'range keys from in-memory index tree' (duration: 135.173248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:14.425489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.879719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T09:59:14.425595Z","caller":"traceutil/trace.go:171","msg":"trace[898112427] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:697; }","duration":"523.385345ms","start":"2026-04-01T09:59:13.902193Z","end":"2026-04-01T09:59:14.425579Z","steps":["trace[898112427] 'read index received' (duration: 187.753454ms)","trace[898112427] 'applied index is now lower than readState.Index' (duration: 335.630421ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T09:59:14.425737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.54718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T09:59:14.425769Z","caller":"traceutil/trace.go:171","msg":"trace[1266727146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"523.619682ms","start":"2026-04-01T09:59:13.902141Z","end":"2026-04-01T09:59:14.42576Z","steps":["trace[1266727146] 'agreement among raft nodes before linearized reading' (duration: 523.488178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:14.425814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:13.902113Z","time spent":"523.694084ms","remote":"127.0.0.1:39516","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-01T09:59:14.426504Z","caller":"traceutil/trace.go:171","msg":"trace[796257201] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"621.76644ms","start":"2026-04-01T09:59:13.804003Z","end":"2026-04-01T09:59:14.42577Z","steps":["trace[796257201] 'process raft request' (duration: 361.530471ms)","trace[796257201] 'compare' (duration: 259.445118ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T09:59:14.426594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:13.803984Z","time spent":"622.573221ms","remote":"127.0.0.1:49542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T09:59:15.537515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.66585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T09:59:15.537633Z","caller":"traceutil/trace.go:171","msg":"trace[1101971969] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:699; }","duration":"636.727464ms","start":"2026-04-01T09:59:14.900883Z","end":"2026-04-01T09:59:15.537611Z","steps":["trace[1101971969] 'read index received' (duration: 92.821868ms)","trace[1101971969] 'applied index is now lower than readState.Index' (duration: 543.903695ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T09:59:15.537743Z","caller":"traceutil/trace.go:171","msg":"trace[664852708] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"1.097817945s","start":"2026-04-01T09:59:14.43991Z","end":"2026-04-01T09:59:15.537728Z","steps":["trace[664852708] 'process raft request' (duration: 553.866179ms)","trace[664852708] 'compare' (duration: 543.318241ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T09:59:15.537817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:14.439887Z","time spent":"1.097892318s","remote":"127.0.0.1:49500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T09:59:15.537902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.006302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T09:59:15.537932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.066292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-01T09:59:15.537982Z","caller":"traceutil/trace.go:171","msg":"trace[1693083652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"637.109304ms","start":"2026-04-01T09:59:14.90085Z","end":"2026-04-01T09:59:15.537959Z","steps":["trace[1693083652] 'agreement among raft nodes before linearized reading' (duration: 636.96253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:15.538025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:14.900833Z","time spent":"637.184497ms","remote":"127.0.0.1:39514","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-01T09:59:15.538023Z","caller":"traceutil/trace.go:171","msg":"trace[1105147953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"296.200026ms","start":"2026-04-01T09:59:15.241772Z","end":"2026-04-01T09:59:15.537972Z","steps":["trace[1105147953] 'agreement among raft nodes before linearized reading' (duration: 295.991269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:15.538222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.745186ms","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-01T09:59:15.538284Z","caller":"traceutil/trace.go:171","msg":"trace[842397454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:676; }","duration":"286.843039ms","start":"2026-04-01T09:59:15.251425Z","end":"2026-04-01T09:59:15.538268Z","steps":["trace[842397454] 'agreement among raft nodes before linearized reading' (duration: 286.738486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:15.53874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.195824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-01T09:59:15.538801Z","caller":"traceutil/trace.go:171","msg":"trace[928604633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"201.290177ms","start":"2026-04-01T09:59:15.337496Z","end":"2026-04-01T09:59:15.538786Z","steps":["trace[928604633] 'agreement among raft nodes before linearized reading' (duration: 200.551688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:15.537989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.206087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-01T09:59:15.539464Z","caller":"traceutil/trace.go:171","msg":"trace[897635742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:676; }","duration":"189.720545ms","start":"2026-04-01T09:59:15.349722Z","end":"2026-04-01T09:59:15.539442Z","steps":["trace[897635742] 'agreement among raft nodes before linearized reading' (duration: 188.079623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:17.096914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.830356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T09:59:17.097033Z","caller":"traceutil/trace.go:171","msg":"trace[2054190712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"194.99678ms","start":"2026-04-01T09:59:16.902011Z","end":"2026-04-01T09:59:17.097007Z","steps":["trace[2054190712] 'range keys from in-memory index tree' (duration: 194.697092ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:17.099868Z","caller":"traceutil/trace.go:171","msg":"trace[330334677] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"111.671623ms","start":"2026-04-01T09:59:16.988181Z","end":"2026-04-01T09:59:17.099852Z","steps":["trace[330334677] 'process raft request' (duration: 111.54192ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:27.848293Z","caller":"traceutil/trace.go:171","msg":"trace[1178166372] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"194.498527ms","start":"2026-04-01T09:59:27.65377Z","end":"2026-04-01T09:59:27.848269Z","steps":["trace[1178166372] 'process raft request' (duration: 192.155114ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:27.850729Z","caller":"traceutil/trace.go:171","msg":"trace[1110508024] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"196.564484ms","start":"2026-04-01T09:59:27.654143Z","end":"2026-04-01T09:59:27.850708Z","steps":["trace[1110508024] 'process raft request' (duration: 196.003021ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:27.85092Z","caller":"traceutil/trace.go:171","msg":"trace[1314841884] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"194.20873ms","start":"2026-04-01T09:59:27.656663Z","end":"2026-04-01T09:59:27.850872Z","steps":["trace[1314841884] 'process raft request' (duration: 193.571186ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:27.850906Z","caller":"traceutil/trace.go:171","msg":"trace[642990837] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"194.085598ms","start":"2026-04-01T09:59:27.656713Z","end":"2026-04-01T09:59:27.850799Z","steps":["trace[642990837] 'process raft request' (duration: 193.555116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:30.849042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.83835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T09:59:30.849143Z","caller":"traceutil/trace.go:171","msg":"trace[37323215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:833; }","duration":"944.951222ms","start":"2026-04-01T09:59:29.904165Z","end":"2026-04-01T09:59:30.849117Z","steps":["trace[37323215] 'range keys from in-memory index tree' (duration: 944.713037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:30.849199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.435201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-01T09:59:30.849192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.093777ms","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-01T09:59:30.8493Z","caller":"traceutil/trace.go:171","msg":"trace[1136171178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:833; }","duration":"994.23868ms","start":"2026-04-01T09:59:29.855048Z","end":"2026-04-01T09:59:30.849287Z","steps":["trace[1136171178] 'range keys from in-memory index tree' (duration: 993.914132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:30.849297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.739089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-01T09:59:30.849335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:29.855031Z","time spent":"994.296112ms","remote":"127.0.0.1:49568","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-01T09:59:30.849359Z","caller":"traceutil/trace.go:171","msg":"trace[464218988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:833; }","duration":"993.806801ms","start":"2026-04-01T09:59:29.85553Z","end":"2026-04-01T09:59:30.849336Z","steps":["trace[464218988] 'range keys from in-memory index tree' (duration: 993.644617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:30.849423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:29.855522Z","time spent":"993.891532ms","remote":"127.0.0.1:49568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T09:59:30.849502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031749305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kz6cw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-01T09:59:30.849533Z","caller":"traceutil/trace.go:171","msg":"trace[887144016] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kz6cw; range_end:; response_count:1; response_revision:833; }","duration":"1.031815026s","start":"2026-04-01T09:59:29.817708Z","end":"2026-04-01T09:59:30.849524Z","steps":["trace[887144016] 'range keys from in-memory index tree' (duration: 1.031653062s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:30.849555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:29.817687Z","time spent":"1.031861797s","remote":"127.0.0.1:49500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kz6cw\" "} {"level":"warn","ts":"2026-04-01T09:59:30.849585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.322718ms","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-01T09:59:30.849626Z","caller":"traceutil/trace.go:171","msg":"trace[953480393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:833; }","duration":"997.38609ms","start":"2026-04-01T09:59:29.852225Z","end":"2026-04-01T09:59:30.849611Z","steps":["trace[953480393] 'range keys from in-memory index tree' (duration: 997.223006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:30.849212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:29.904113Z","time spent":"945.083575ms","remote":"127.0.0.1:39514","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-01T09:59:30.849682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:29.85221Z","time spent":"997.462501ms","remote":"127.0.0.1:49568","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-01T09:59:30.849704Z","caller":"traceutil/trace.go:171","msg":"trace[1093751008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:833; }","duration":"993.560314ms","start":"2026-04-01T09:59:29.855703Z","end":"2026-04-01T09:59:30.849264Z","steps":["trace[1093751008] 'range keys from in-memory index tree' (duration: 993.276318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:30.84973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T09:59:29.855689Z","time spent":"994.034635ms","remote":"127.0.0.1:49568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T09:59:30.970012Z","caller":"traceutil/trace.go:171","msg":"trace[244134089] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"115.789566ms","start":"2026-04-01T09:59:30.854201Z","end":"2026-04-01T09:59:30.96999Z","steps":["trace[244134089] 'process raft request' (duration: 115.643273ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:30.977526Z","caller":"traceutil/trace.go:171","msg":"trace[294421375] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"122.464164ms","start":"2026-04-01T09:59:30.855051Z","end":"2026-04-01T09:59:30.977515Z","steps":["trace[294421375] 'process raft request' (duration: 122.193578ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:30.97772Z","caller":"traceutil/trace.go:171","msg":"trace[1760067710] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"122.564357ms","start":"2026-04-01T09:59:30.855128Z","end":"2026-04-01T09:59:30.977693Z","steps":["trace[1760067710] 'process raft request' (duration: 122.198369ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:30.977906Z","caller":"traceutil/trace.go:171","msg":"trace[435787673] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"122.462654ms","start":"2026-04-01T09:59:30.855415Z","end":"2026-04-01T09:59:30.977878Z","steps":["trace[435787673] 'process raft request' (duration: 121.936052ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:30.978316Z","caller":"traceutil/trace.go:171","msg":"trace[875946658] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"118.480496ms","start":"2026-04-01T09:59:30.859454Z","end":"2026-04-01T09:59:30.977934Z","steps":["trace[875946658] 'process raft request' (duration: 118.362433ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:30.977885Z","caller":"traceutil/trace.go:171","msg":"trace[402719003] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:863; }","duration":"114.379346ms","start":"2026-04-01T09:59:30.863497Z","end":"2026-04-01T09:59:30.977876Z","steps":["trace[402719003] 'read index received' (duration: 106.334138ms)","trace[402719003] 'applied index is now lower than readState.Index' (duration: 8.044638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T09:59:30.979135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.622064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-01T09:59:30.979217Z","caller":"traceutil/trace.go:171","msg":"trace[1349376788] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:839; }","duration":"115.726786ms","start":"2026-04-01T09:59:30.863476Z","end":"2026-04-01T09:59:30.979202Z","steps":["trace[1349376788] 'agreement among raft nodes before linearized reading' (duration: 115.567721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T09:59:45.131958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.490954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-01T09:59:45.131955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.432885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-01T09:59:45.132034Z","caller":"traceutil/trace.go:171","msg":"trace[1586237487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:992; }","duration":"105.622306ms","start":"2026-04-01T09:59:45.026396Z","end":"2026-04-01T09:59:45.132018Z","steps":["trace[1586237487] 'range keys from in-memory index tree' (duration: 105.30051ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T09:59:45.132052Z","caller":"traceutil/trace.go:171","msg":"trace[2000623018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:992; }","duration":"101.589718ms","start":"2026-04-01T09:59:45.030443Z","end":"2026-04-01T09:59:45.132033Z","steps":["trace[2000623018] 'range keys from in-memory index tree' (duration: 101.254522ms)"],"step_count":1}