{"level":"warn","ts":"2026-02-25T17:12:25.344093Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T17:12:25.344242Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-02-25T17:12:25.344329Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T17:12:25.344346Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-02-25T17:12:25.344382Z","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-02-25T17:12:25.345385Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-02-25T17:12:25.345487Z","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.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-02-25T17:12:25.35373Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.970095ms"} {"level":"info","ts":"2026-02-25T17:12:25.362717Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-02-25T17:12:25.362805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T17:12:25.362833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-02-25T17:12:25.362844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T17:12:25.362852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-02-25T17:12:25.364495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-02-25T17:12:25.375117Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T17:12:25.376621Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T17:12:25.377702Z","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-02-25T17:12:25.379521Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T17:12:25.385486Z","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-02-25T17:12:25.388846Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-02-25T17:12:25.388962Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-02-25T17:12:25.389019Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T17:12:25.389073Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T17:12:25.389274Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T17:12:25.389321Z","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-02-25T17:12:25.389358Z","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-02-25T17:12:25.389366Z","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-02-25T17:12:25.393351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-02-25T17:12:25.393683Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-02-25T17:12:25.86519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T17:12:25.865235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T17:12:25.865267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-02-25T17:12:25.865314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-02-25T17:12:25.865328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-02-25T17:12:25.865339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-02-25T17:12:25.865349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-02-25T17:12:25.86723Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:12:25.868349Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T17:12:25.868347Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T17:12:25.868414Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T17:12:25.868761Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T17:12:25.868847Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T17:12:25.870687Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:12:25.870858Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:12:25.870911Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:12:25.871087Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T17:12:25.871966Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"warn","ts":"2026-02-25T17:12:35.464274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.908709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T17:12:35.464557Z","caller":"traceutil/trace.go:171","msg":"trace[1690866795] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:335; }","duration":"181.254837ms","start":"2026-02-25T17:12:35.283271Z","end":"2026-02-25T17:12:35.464526Z","steps":["trace[1690866795] 'range keys from in-memory index tree' (duration: 180.782307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:35.464338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.033495ms","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-02-25T17:12:35.4649Z","caller":"traceutil/trace.go:171","msg":"trace[584474185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:335; }","duration":"188.663778ms","start":"2026-02-25T17:12:35.276166Z","end":"2026-02-25T17:12:35.46483Z","steps":["trace[584474185] 'range keys from in-memory index tree' (duration: 187.844431ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:35.72204Z","caller":"traceutil/trace.go:171","msg":"trace[2019035025] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"252.143945ms","start":"2026-02-25T17:12:35.46987Z","end":"2026-02-25T17:12:35.722014Z","steps":["trace[2019035025] 'process raft request' (duration: 251.979502ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.183115Z","caller":"traceutil/trace.go:171","msg":"trace[578834892] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:344; }","duration":"621.566069ms","start":"2026-02-25T17:12:35.561514Z","end":"2026-02-25T17:12:36.18308Z","steps":["trace[578834892] 'read index received' (duration: 160.319575ms)","trace[578834892] 'applied index is now lower than readState.Index' (duration: 461.245174ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:12:36.183234Z","caller":"traceutil/trace.go:171","msg":"trace[439022293] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"713.186975ms","start":"2026-02-25T17:12:35.470001Z","end":"2026-02-25T17:12:36.183188Z","steps":["trace[439022293] 'process raft request' (duration: 712.879148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.183469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.912087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-02-25T17:12:36.183574Z","caller":"traceutil/trace.go:171","msg":"trace[1627513099] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:337; }","duration":"622.05496ms","start":"2026-02-25T17:12:35.561464Z","end":"2026-02-25T17:12:36.183519Z","steps":["trace[1627513099] 'agreement among raft nodes before linearized reading' (duration: 621.885206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.183619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.658065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T17:12:36.183655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:12:35.561437Z","time spent":"622.206593ms","remote":"127.0.0.1:47346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-25T17:12:36.183749Z","caller":"traceutil/trace.go:171","msg":"trace[1100460474] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:337; }","duration":"457.837089ms","start":"2026-02-25T17:12:35.725886Z","end":"2026-02-25T17:12:36.183723Z","steps":["trace[1100460474] 'agreement among raft nodes before linearized reading' (duration: 457.630285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.183723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.532868ms","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-02-25T17:12:36.183819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:12:35.725864Z","time spent":"457.944781ms","remote":"127.0.0.1:47352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"info","ts":"2026-02-25T17:12:36.183849Z","caller":"traceutil/trace.go:171","msg":"trace[442290832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:337; }","duration":"596.742713ms","start":"2026-02-25T17:12:35.587079Z","end":"2026-02-25T17:12:36.183821Z","steps":["trace[442290832] 'agreement among raft nodes before linearized reading' (duration: 596.426516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.183927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:12:35.587062Z","time spent":"596.852495ms","remote":"127.0.0.1:47416","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-02-25T17:12:36.183943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:12:35.469976Z","time spent":"713.347127ms","remote":"127.0.0.1:47416","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-02-25T17:12:36.407231Z","caller":"traceutil/trace.go:171","msg":"trace[1178904071] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"217.997455ms","start":"2026-02-25T17:12:36.189188Z","end":"2026-02-25T17:12:36.407185Z","steps":["trace[1178904071] 'process raft request' (duration: 131.480242ms)","trace[1178904071] 'compare' (duration: 86.329348ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:12:36.443099Z","caller":"traceutil/trace.go:171","msg":"trace[1313894624] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"247.002282ms","start":"2026-02-25T17:12:36.196079Z","end":"2026-02-25T17:12:36.443081Z","steps":["trace[1313894624] 'process raft request' (duration: 246.693806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.44326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.814313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T17:12:36.443058Z","caller":"traceutil/trace.go:171","msg":"trace[1186661381] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:346; }","duration":"234.605309ms","start":"2026-02-25T17:12:36.208417Z","end":"2026-02-25T17:12:36.443022Z","steps":["trace[1186661381] 'read index received' (duration: 112.333661ms)","trace[1186661381] 'applied index is now lower than readState.Index' (duration: 122.269848ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:12:36.443424Z","caller":"traceutil/trace.go:171","msg":"trace[1556718183] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:339; }","duration":"234.941056ms","start":"2026-02-25T17:12:36.208371Z","end":"2026-02-25T17:12:36.443312Z","steps":["trace[1556718183] 'agreement among raft nodes before linearized reading' (duration: 234.767553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.44358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.530148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T17:12:36.443674Z","caller":"traceutil/trace.go:171","msg":"trace[508979905] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:339; }","duration":"229.691802ms","start":"2026-02-25T17:12:36.213959Z","end":"2026-02-25T17:12:36.443651Z","steps":["trace[508979905] 'agreement among raft nodes before linearized reading' (duration: 229.490887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.443801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.321352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T17:12:36.443849Z","caller":"traceutil/trace.go:171","msg":"trace[134200028] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:339; }","duration":"168.410444ms","start":"2026-02-25T17:12:36.275426Z","end":"2026-02-25T17:12:36.443837Z","steps":["trace[134200028] 'agreement among raft nodes before linearized reading' (duration: 168.327972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.443986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.178738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-25T17:12:36.444041Z","caller":"traceutil/trace.go:171","msg":"trace[12021997] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:339; }","duration":"177.262249ms","start":"2026-02-25T17:12:36.266765Z","end":"2026-02-25T17:12:36.444027Z","steps":["trace[12021997] 'agreement among raft nodes before linearized reading' (duration: 177.165237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.444175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.461773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-02-25T17:12:36.444269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.39796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T17:12:36.444287Z","caller":"traceutil/trace.go:171","msg":"trace[1944239982] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:339; }","duration":"152.653698ms","start":"2026-02-25T17:12:36.291607Z","end":"2026-02-25T17:12:36.444261Z","steps":["trace[1944239982] 'agreement among raft nodes before linearized reading' (duration: 152.385472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.444361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.434823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-25T17:12:36.444401Z","caller":"traceutil/trace.go:171","msg":"trace[1662466852] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:339; }","duration":"197.524955ms","start":"2026-02-25T17:12:36.246867Z","end":"2026-02-25T17:12:36.444392Z","steps":["trace[1662466852] 'agreement among raft nodes before linearized reading' (duration: 197.459183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.444395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.222253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-25T17:12:36.444455Z","caller":"traceutil/trace.go:171","msg":"trace[1910274739] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:339; }","duration":"210.304275ms","start":"2026-02-25T17:12:36.234136Z","end":"2026-02-25T17:12:36.44444Z","steps":["trace[1910274739] 'agreement among raft nodes before linearized reading' (duration: 210.196132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.444585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.96179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-25T17:12:36.444651Z","caller":"traceutil/trace.go:171","msg":"trace[2078924434] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:339; }","duration":"201.077152ms","start":"2026-02-25T17:12:36.243558Z","end":"2026-02-25T17:12:36.444635Z","steps":["trace[2078924434] 'agreement among raft nodes before linearized reading' (duration: 200.96831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.444709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.093981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-25T17:12:36.444763Z","caller":"traceutil/trace.go:171","msg":"trace[75677378] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:339; }","duration":"100.157952ms","start":"2026-02-25T17:12:36.344587Z","end":"2026-02-25T17:12:36.444745Z","steps":["trace[75677378] 'agreement among raft nodes before linearized reading' (duration: 100.07162ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.444306Z","caller":"traceutil/trace.go:171","msg":"trace[944842309] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:339; }","duration":"206.465871ms","start":"2026-02-25T17:12:36.237828Z","end":"2026-02-25T17:12:36.444294Z","steps":["trace[944842309] 'agreement among raft nodes before linearized reading' (duration: 206.404009ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.563097Z","caller":"traceutil/trace.go:171","msg":"trace[1632967971] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"104.59446ms","start":"2026-02-25T17:12:36.458459Z","end":"2026-02-25T17:12:36.563054Z","steps":["trace[1632967971] 'process raft request' (duration: 95.476329ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.56331Z","caller":"traceutil/trace.go:171","msg":"trace[1729419126] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"104.539848ms","start":"2026-02-25T17:12:36.458733Z","end":"2026-02-25T17:12:36.563273Z","steps":["trace[1729419126] 'process raft request' (duration: 104.225722ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.563671Z","caller":"traceutil/trace.go:171","msg":"trace[1803797252] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"104.683532ms","start":"2026-02-25T17:12:36.45897Z","end":"2026-02-25T17:12:36.563653Z","steps":["trace[1803797252] 'process raft request' (duration: 104.16025ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.563869Z","caller":"traceutil/trace.go:171","msg":"trace[1858340882] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"104.945038ms","start":"2026-02-25T17:12:36.458894Z","end":"2026-02-25T17:12:36.563839Z","steps":["trace[1858340882] 'process raft request' (duration: 104.17756ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.564104Z","caller":"traceutil/trace.go:171","msg":"trace[1864572678] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"103.751901ms","start":"2026-02-25T17:12:36.460294Z","end":"2026-02-25T17:12:36.564046Z","steps":["trace[1864572678] 'process raft request' (duration: 102.981964ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.564162Z","caller":"traceutil/trace.go:171","msg":"trace[1016682825] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"103.026115ms","start":"2026-02-25T17:12:36.461125Z","end":"2026-02-25T17:12:36.564151Z","steps":["trace[1016682825] 'process raft request' (duration: 102.820631ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.56442Z","caller":"traceutil/trace.go:171","msg":"trace[518467955] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"103.322333ms","start":"2026-02-25T17:12:36.461068Z","end":"2026-02-25T17:12:36.56439Z","steps":["trace[518467955] 'process raft request' (duration: 102.977255ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.564509Z","caller":"traceutil/trace.go:171","msg":"trace[2105257878] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"102.511455ms","start":"2026-02-25T17:12:36.461983Z","end":"2026-02-25T17:12:36.564494Z","steps":["trace[2105257878] 'process raft request' (duration: 102.30055ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.564556Z","caller":"traceutil/trace.go:171","msg":"trace[1848488487] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"101.906122ms","start":"2026-02-25T17:12:36.462641Z","end":"2026-02-25T17:12:36.564548Z","steps":["trace[1848488487] 'process raft request' (duration: 101.722368ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.564523Z","caller":"traceutil/trace.go:171","msg":"trace[776120724] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"101.43029ms","start":"2026-02-25T17:12:36.463082Z","end":"2026-02-25T17:12:36.564512Z","steps":["trace[776120724] 'process raft request' (duration: 101.370259ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.564688Z","caller":"traceutil/trace.go:171","msg":"trace[1950009970] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"103.277341ms","start":"2026-02-25T17:12:36.461398Z","end":"2026-02-25T17:12:36.564676Z","steps":["trace[1950009970] 'process raft request' (duration: 102.725729ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.565115Z","caller":"traceutil/trace.go:171","msg":"trace[901596319] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"101.118544ms","start":"2026-02-25T17:12:36.463746Z","end":"2026-02-25T17:12:36.564865Z","steps":["trace[901596319] 'process raft request' (duration: 100.740276ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.778461Z","caller":"traceutil/trace.go:171","msg":"trace[1456495061] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"204.223811ms","start":"2026-02-25T17:12:36.574185Z","end":"2026-02-25T17:12:36.778409Z","steps":["trace[1456495061] 'process raft request' (duration: 114.939387ms)","trace[1456495061] 'compare' (duration: 88.523118ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:12:36.778497Z","caller":"traceutil/trace.go:171","msg":"trace[764559182] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"203.113696ms","start":"2026-02-25T17:12:36.575352Z","end":"2026-02-25T17:12:36.778466Z","steps":["trace[764559182] 'process raft request' (duration: 202.729238ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.778594Z","caller":"traceutil/trace.go:171","msg":"trace[1280421310] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"203.085336ms","start":"2026-02-25T17:12:36.575454Z","end":"2026-02-25T17:12:36.77854Z","steps":["trace[1280421310] 'process raft request' (duration: 202.751189ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.779358Z","caller":"traceutil/trace.go:171","msg":"trace[1608058504] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"202.871501ms","start":"2026-02-25T17:12:36.57645Z","end":"2026-02-25T17:12:36.779322Z","steps":["trace[1608058504] 'process raft request' (duration: 202.651776ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.779452Z","caller":"traceutil/trace.go:171","msg":"trace[1099523925] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"164.462796ms","start":"2026-02-25T17:12:36.614971Z","end":"2026-02-25T17:12:36.779433Z","steps":["trace[1099523925] 'process raft request' (duration: 164.401525ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.779659Z","caller":"traceutil/trace.go:171","msg":"trace[1977019692] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"202.082125ms","start":"2026-02-25T17:12:36.57756Z","end":"2026-02-25T17:12:36.779642Z","steps":["trace[1977019692] 'process raft request' (duration: 201.652654ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.779869Z","caller":"traceutil/trace.go:171","msg":"trace[1505094176] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:363; }","duration":"196.709927ms","start":"2026-02-25T17:12:36.583138Z","end":"2026-02-25T17:12:36.779847Z","steps":["trace[1505094176] 'read index received' (duration: 25.46929ms)","trace[1505094176] 'applied index is now lower than readState.Index' (duration: 171.238097ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:12:36.780061Z","caller":"traceutil/trace.go:171","msg":"trace[1342073823] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"203.948596ms","start":"2026-02-25T17:12:36.576071Z","end":"2026-02-25T17:12:36.78002Z","steps":["trace[1342073823] 'process raft request' (duration: 202.186737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.780125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.950312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T17:12:36.780173Z","caller":"traceutil/trace.go:171","msg":"trace[2087267705] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:364; }","duration":"197.056074ms","start":"2026-02-25T17:12:36.583103Z","end":"2026-02-25T17:12:36.780159Z","steps":["trace[2087267705] 'agreement among raft nodes before linearized reading' (duration: 196.926941ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.780185Z","caller":"traceutil/trace.go:171","msg":"trace[1302584237] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"166.948962ms","start":"2026-02-25T17:12:36.613216Z","end":"2026-02-25T17:12:36.780165Z","steps":["trace[1302584237] 'process raft request' (duration: 166.061622ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.779613Z","caller":"traceutil/trace.go:171","msg":"trace[484912937] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"202.010214ms","start":"2026-02-25T17:12:36.577543Z","end":"2026-02-25T17:12:36.779553Z","steps":["trace[484912937] 'process raft request' (duration: 201.620584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.780387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.556992ms","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-02-25T17:12:36.78043Z","caller":"traceutil/trace.go:171","msg":"trace[1848585442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:364; }","duration":"175.631542ms","start":"2026-02-25T17:12:36.604786Z","end":"2026-02-25T17:12:36.780418Z","steps":["trace[1848585442] 'agreement among raft nodes before linearized reading' (duration: 175.555291ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.780443Z","caller":"traceutil/trace.go:171","msg":"trace[1129409286] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"166.479982ms","start":"2026-02-25T17:12:36.613949Z","end":"2026-02-25T17:12:36.780429Z","steps":["trace[1129409286] 'process raft request' (duration: 165.368997ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.946762Z","caller":"traceutil/trace.go:171","msg":"trace[1114747211] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"158.096267ms","start":"2026-02-25T17:12:36.788637Z","end":"2026-02-25T17:12:36.946733Z","steps":["trace[1114747211] 'process raft request' (duration: 124.234122ms)","trace[1114747211] 'compare' (duration: 33.477646ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:12:36.947048Z","caller":"traceutil/trace.go:171","msg":"trace[367873650] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"157.737408ms","start":"2026-02-25T17:12:36.7893Z","end":"2026-02-25T17:12:36.947037Z","steps":["trace[367873650] 'process raft request' (duration: 157.334329ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.947165Z","caller":"traceutil/trace.go:171","msg":"trace[231478726] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"156.370348ms","start":"2026-02-25T17:12:36.790781Z","end":"2026-02-25T17:12:36.947152Z","steps":["trace[231478726] 'process raft request' (duration: 155.937958ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:36.947288Z","caller":"traceutil/trace.go:171","msg":"trace[570552731] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"155.254774ms","start":"2026-02-25T17:12:36.792019Z","end":"2026-02-25T17:12:36.947274Z","steps":["trace[570552731] 'process raft request' (duration: 154.807515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:36.947599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.992412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-02-25T17:12:36.947722Z","caller":"traceutil/trace.go:171","msg":"trace[1921645486] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:373; }","duration":"157.143587ms","start":"2026-02-25T17:12:36.790546Z","end":"2026-02-25T17:12:36.947689Z","steps":["trace[1921645486] 'agreement among raft nodes before linearized reading' (duration: 156.920641ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:50.515653Z","caller":"traceutil/trace.go:171","msg":"trace[1620106814] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"237.593055ms","start":"2026-02-25T17:12:50.278013Z","end":"2026-02-25T17:12:50.515606Z","steps":["trace[1620106814] 'process raft request' (duration: 237.399191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:51.234603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.623847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:12:51.234803Z","caller":"traceutil/trace.go:171","msg":"trace[274979922] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:473; }","duration":"318.893363ms","start":"2026-02-25T17:12:50.915823Z","end":"2026-02-25T17:12:51.234717Z","steps":["trace[274979922] 'read index received' (duration: 195.270263ms)","trace[274979922] 'applied index is now lower than readState.Index' (duration: 123.62103ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:12:51.234913Z","caller":"traceutil/trace.go:171","msg":"trace[1025168169] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"714.858272ms","start":"2026-02-25T17:12:50.520033Z","end":"2026-02-25T17:12:51.234891Z","steps":["trace[1025168169] 'process raft request' (duration: 591.14027ms)","trace[1025168169] 'compare' (duration: 122.512494ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:12:51.235139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.295672ms","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-02-25T17:12:51.235293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:12:50.520005Z","time spent":"715.001105ms","remote":"127.0.0.1:47416","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-02-25T17:12:51.235546Z","caller":"traceutil/trace.go:171","msg":"trace[1916806400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:459; }","duration":"319.436084ms","start":"2026-02-25T17:12:50.915776Z","end":"2026-02-25T17:12:51.235212Z","steps":["trace[1916806400] 'agreement among raft nodes before linearized reading' (duration: 319.134988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:51.235604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:12:50.915758Z","time spent":"319.834204ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T17:12:56.439885Z","caller":"traceutil/trace.go:171","msg":"trace[229469561] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"167.085265ms","start":"2026-02-25T17:12:56.272771Z","end":"2026-02-25T17:12:56.439857Z","steps":["trace[229469561] 'read index received' (duration: 159.707223ms)","trace[229469561] 'applied index is now lower than readState.Index' (duration: 7.376922ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:12:56.439916Z","caller":"traceutil/trace.go:171","msg":"trace[569670264] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"172.220778ms","start":"2026-02-25T17:12:56.267661Z","end":"2026-02-25T17:12:56.439882Z","steps":["trace[569670264] 'process raft request' (duration: 164.751353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:56.440249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.709297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fvhjh\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-02-25T17:12:56.44034Z","caller":"traceutil/trace.go:171","msg":"trace[1586003682] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fvhjh; range_end:; response_count:1; response_revision:495; }","duration":"171.834769ms","start":"2026-02-25T17:12:56.268484Z","end":"2026-02-25T17:12:56.440319Z","steps":["trace[1586003682] 'agreement among raft nodes before linearized reading' (duration: 171.528323ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:56.440427Z","caller":"traceutil/trace.go:171","msg":"trace[2121886661] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"156.121464ms","start":"2026-02-25T17:12:56.284291Z","end":"2026-02-25T17:12:56.440413Z","steps":["trace[2121886661] 'process raft request' (duration: 155.756166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:56.441163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.503768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4309"} {"level":"info","ts":"2026-02-25T17:12:56.441292Z","caller":"traceutil/trace.go:171","msg":"trace[1910062982] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:497; }","duration":"159.686582ms","start":"2026-02-25T17:12:56.281577Z","end":"2026-02-25T17:12:56.441264Z","steps":["trace[1910062982] 'agreement among raft nodes before linearized reading' (duration: 159.318314ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:56.441435Z","caller":"traceutil/trace.go:171","msg":"trace[362863902] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"111.894711ms","start":"2026-02-25T17:12:56.329492Z","end":"2026-02-25T17:12:56.441387Z","steps":["trace[362863902] 'process raft request' (duration: 110.92989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:56.441934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.194309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-02-25T17:12:56.441994Z","caller":"traceutil/trace.go:171","msg":"trace[1230090641] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:497; }","duration":"153.26489ms","start":"2026-02-25T17:12:56.288714Z","end":"2026-02-25T17:12:56.441978Z","steps":["trace[1230090641] 'agreement among raft nodes before linearized reading' (duration: 153.044175ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:58.115629Z","caller":"traceutil/trace.go:171","msg":"trace[1392631931] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"247.338739ms","start":"2026-02-25T17:12:57.868267Z","end":"2026-02-25T17:12:58.115606Z","steps":["trace[1392631931] 'process raft request' (duration: 247.192096ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:59.388976Z","caller":"traceutil/trace.go:171","msg":"trace[916357120] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"205.603572ms","start":"2026-02-25T17:12:59.183357Z","end":"2026-02-25T17:12:59.388961Z","steps":["trace[916357120] 'read index received' (duration: 205.552131ms)","trace[916357120] 'applied index is now lower than readState.Index' (duration: 50.571µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:12:59.389004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:12:59.030051Z","time spent":"358.947194ms","remote":"127.0.0.1:47198","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T17:12:59.38908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.717384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:12:59.389104Z","caller":"traceutil/trace.go:171","msg":"trace[1912200387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"205.776725ms","start":"2026-02-25T17:12:59.18332Z","end":"2026-02-25T17:12:59.389096Z","steps":["trace[1912200387] 'agreement among raft nodes before linearized reading' (duration: 205.726224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:59.389241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.114968ms","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-02-25T17:12:59.38926Z","caller":"traceutil/trace.go:171","msg":"trace[1008654251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"118.170189ms","start":"2026-02-25T17:12:59.271086Z","end":"2026-02-25T17:12:59.389256Z","steps":["trace[1008654251] 'agreement among raft nodes before linearized reading' (duration: 118.130998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:59.389338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.347551ms","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-02-25T17:12:59.389355Z","caller":"traceutil/trace.go:171","msg":"trace[1177534417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"112.381421ms","start":"2026-02-25T17:12:59.27697Z","end":"2026-02-25T17:12:59.389352Z","steps":["trace[1177534417] 'agreement among raft nodes before linearized reading' (duration: 112.351021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:59.389444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.658459ms","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-02-25T17:12:59.389488Z","caller":"traceutil/trace.go:171","msg":"trace[1540029419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"112.73645ms","start":"2026-02-25T17:12:59.276735Z","end":"2026-02-25T17:12:59.389472Z","steps":["trace[1540029419] 'agreement among raft nodes before linearized reading' (duration: 112.641827ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:59.521554Z","caller":"traceutil/trace.go:171","msg":"trace[130281536] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"131.198646ms","start":"2026-02-25T17:12:59.390334Z","end":"2026-02-25T17:12:59.521532Z","steps":["trace[130281536] 'read index received' (duration: 127.750649ms)","trace[130281536] 'applied index is now lower than readState.Index' (duration: 3.447167ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:12:59.521606Z","caller":"traceutil/trace.go:171","msg":"trace[1251552749] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"131.642205ms","start":"2026-02-25T17:12:59.389942Z","end":"2026-02-25T17:12:59.521584Z","steps":["trace[1251552749] 'process raft request' (duration: 128.131977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:12:59.52172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.365559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:12:59.521756Z","caller":"traceutil/trace.go:171","msg":"trace[1797799492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"131.43939ms","start":"2026-02-25T17:12:59.390307Z","end":"2026-02-25T17:12:59.521746Z","steps":["trace[1797799492] 'agreement among raft nodes before linearized reading' (duration: 131.309648ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:59.522531Z","caller":"traceutil/trace.go:171","msg":"trace[1404004113] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"128.646759ms","start":"2026-02-25T17:12:59.393869Z","end":"2026-02-25T17:12:59.522516Z","steps":["trace[1404004113] 'process raft request' (duration: 128.609918ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:59.522735Z","caller":"traceutil/trace.go:171","msg":"trace[1134768044] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"129.313254ms","start":"2026-02-25T17:12:59.393408Z","end":"2026-02-25T17:12:59.522721Z","steps":["trace[1134768044] 'process raft request' (duration: 129.037088ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:12:59.522726Z","caller":"traceutil/trace.go:171","msg":"trace[716048882] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"129.419796ms","start":"2026-02-25T17:12:59.393289Z","end":"2026-02-25T17:12:59.522709Z","steps":["trace[716048882] 'process raft request' (duration: 129.083609ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:13:04.13417Z","caller":"traceutil/trace.go:171","msg":"trace[1543049220] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"169.893246ms","start":"2026-02-25T17:13:03.964252Z","end":"2026-02-25T17:13:04.134145Z","steps":["trace[1543049220] 'process raft request' (duration: 169.734442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:04.285789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.905532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:13:04.28588Z","caller":"traceutil/trace.go:171","msg":"trace[717942685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"103.086786ms","start":"2026-02-25T17:13:04.182773Z","end":"2026-02-25T17:13:04.285859Z","steps":["trace[717942685] 'range keys from in-memory index tree' (duration: 102.777749ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:13:04.86555Z","caller":"traceutil/trace.go:171","msg":"trace[1366902100] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"283.798351ms","start":"2026-02-25T17:13:04.581719Z","end":"2026-02-25T17:13:04.865517Z","steps":["trace[1366902100] 'read index received' (duration: 239.436705ms)","trace[1366902100] 'applied index is now lower than readState.Index' (duration: 44.359916ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:13:04.865735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.996915ms","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-02-25T17:13:04.865829Z","caller":"traceutil/trace.go:171","msg":"trace[1488449075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"284.136068ms","start":"2026-02-25T17:13:04.581668Z","end":"2026-02-25T17:13:04.865804Z","steps":["trace[1488449075] 'agreement among raft nodes before linearized reading' (duration: 283.959624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:06.150868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.01384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:13:06.150956Z","caller":"traceutil/trace.go:171","msg":"trace[511229544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"969.164964ms","start":"2026-02-25T17:13:05.181772Z","end":"2026-02-25T17:13:06.150937Z","steps":["trace[511229544] 'range keys from in-memory index tree' (duration: 968.940589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:06.150924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.964192ms","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-02-25T17:13:06.151005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:05.181752Z","time spent":"969.243176ms","remote":"127.0.0.1:47156","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-02-25T17:13:06.151092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.057179ms","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-02-25T17:13:06.151019Z","caller":"traceutil/trace.go:171","msg":"trace[2116043496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"604.112605ms","start":"2026-02-25T17:13:05.546887Z","end":"2026-02-25T17:13:06.150999Z","steps":["trace[2116043496] 'range keys from in-memory index tree' (duration: 603.794119ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:13:06.151209Z","caller":"traceutil/trace.go:171","msg":"trace[2013028327] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:529; }","duration":"988.156471ms","start":"2026-02-25T17:13:05.163025Z","end":"2026-02-25T17:13:06.151181Z","steps":["trace[2013028327] 'range keys from in-memory index tree' (duration: 988.049609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:06.151239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:05.546866Z","time spent":"604.36064ms","remote":"127.0.0.1:47416","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-02-25T17:13:06.15102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.302788ms","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-02-25T17:13:06.151371Z","caller":"traceutil/trace.go:171","msg":"trace[959709375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"603.698636ms","start":"2026-02-25T17:13:05.547652Z","end":"2026-02-25T17:13:06.151351Z","steps":["trace[959709375] 'range keys from in-memory index tree' (duration: 603.164175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:06.151434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:05.547633Z","time spent":"603.783018ms","remote":"127.0.0.1:47416","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-02-25T17:13:06.151066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.112905ms","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-02-25T17:13:06.15152Z","caller":"traceutil/trace.go:171","msg":"trace[1989664619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"604.598376ms","start":"2026-02-25T17:13:05.546906Z","end":"2026-02-25T17:13:06.151505Z","steps":["trace[1989664619] 'range keys from in-memory index tree' (duration: 604.032453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:06.151559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:05.546886Z","time spent":"604.663747ms","remote":"127.0.0.1:47416","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-02-25T17:13:07.354759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.715549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:13:07.354859Z","caller":"traceutil/trace.go:171","msg":"trace[1115946571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"172.869242ms","start":"2026-02-25T17:13:07.181972Z","end":"2026-02-25T17:13:07.354842Z","steps":["trace[1115946571] 'range keys from in-memory index tree' (duration: 172.647767ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:13:11.283387Z","caller":"traceutil/trace.go:171","msg":"trace[1795630423] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"102.043463ms","start":"2026-02-25T17:13:11.181307Z","end":"2026-02-25T17:13:11.28335Z","steps":["trace[1795630423] 'process raft request' (duration: 101.794118ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:13:11.283949Z","caller":"traceutil/trace.go:171","msg":"trace[894333640] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:624; }","duration":"101.009502ms","start":"2026-02-25T17:13:11.182908Z","end":"2026-02-25T17:13:11.283917Z","steps":["trace[894333640] 'read index received' (duration: 101.001632ms)","trace[894333640] 'applied index is now lower than readState.Index' (duration: 5.88µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:13:11.284175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.238038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:13:11.284225Z","caller":"traceutil/trace.go:171","msg":"trace[898652214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"101.331729ms","start":"2026-02-25T17:13:11.182876Z","end":"2026-02-25T17:13:11.284208Z","steps":["trace[898652214] 'agreement among raft nodes before linearized reading' (duration: 101.177126ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:13:25.965351Z","caller":"traceutil/trace.go:171","msg":"trace[9179281] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"107.34111ms","start":"2026-02-25T17:13:25.857982Z","end":"2026-02-25T17:13:25.965323Z","steps":["trace[9179281] 'process raft request' (duration: 107.080774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:26.904486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.021667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:13:26.904588Z","caller":"traceutil/trace.go:171","msg":"trace[1515094603] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:715; }","duration":"149.191402ms","start":"2026-02-25T17:13:26.755376Z","end":"2026-02-25T17:13:26.904567Z","steps":["trace[1515094603] 'count revisions from in-memory index tree' (duration: 148.849484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:26.905017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.601674ms","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-02-25T17:13:26.9051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.333097ms","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-02-25T17:13:26.905116Z","caller":"traceutil/trace.go:171","msg":"trace[654723362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:715; }","duration":"633.747108ms","start":"2026-02-25T17:13:26.271346Z","end":"2026-02-25T17:13:26.905093Z","steps":["trace[654723362] 'range keys from in-memory index tree' (duration: 633.40461ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:13:26.905157Z","caller":"traceutil/trace.go:171","msg":"trace[1687278091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:715; }","duration":"597.404139ms","start":"2026-02-25T17:13:26.307736Z","end":"2026-02-25T17:13:26.90514Z","steps":["trace[1687278091] 'range keys from in-memory index tree' (duration: 597.228234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:26.905159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:26.271319Z","time spent":"633.8301ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T17:13:26.905199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:26.307727Z","time spent":"597.46302ms","remote":"127.0.0.1:47416","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-02-25T17:13:26.904586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.624168ms","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-02-25T17:13:26.905328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.151327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:13:26.905365Z","caller":"traceutil/trace.go:171","msg":"trace[1052884162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:715; }","duration":"725.211789ms","start":"2026-02-25T17:13:26.180139Z","end":"2026-02-25T17:13:26.905351Z","steps":["trace[1052884162] 'range keys from in-memory index tree' (duration: 725.090986ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:13:26.905348Z","caller":"traceutil/trace.go:171","msg":"trace[838529129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:715; }","duration":"600.430475ms","start":"2026-02-25T17:13:26.304881Z","end":"2026-02-25T17:13:26.905312Z","steps":["trace[838529129] 'range keys from in-memory index tree' (duration: 599.410172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:26.905389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:26.180119Z","time spent":"725.26539ms","remote":"127.0.0.1:47156","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-02-25T17:13:26.905443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:26.304874Z","time spent":"600.535327ms","remote":"127.0.0.1:47416","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-02-25T17:13:27.376741Z","caller":"traceutil/trace.go:171","msg":"trace[116699527] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"468.065254ms","start":"2026-02-25T17:13:26.908644Z","end":"2026-02-25T17:13:27.37671Z","steps":["trace[116699527] 'process raft request' (duration: 467.728626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:27.376966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:26.908627Z","time spent":"468.224827ms","remote":"127.0.0.1:47416","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-02-25T17:13:27.559709Z","caller":"traceutil/trace.go:171","msg":"trace[1301870895] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"650.313341ms","start":"2026-02-25T17:13:26.909357Z","end":"2026-02-25T17:13:27.559671Z","steps":["trace[1301870895] 'process raft request' (duration: 647.778555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:27.559888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:26.90934Z","time spent":"650.461505ms","remote":"127.0.0.1:47416","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-02-25T17:13:27.560512Z","caller":"traceutil/trace.go:171","msg":"trace[47543169] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:740; }","duration":"543.003582ms","start":"2026-02-25T17:13:27.017488Z","end":"2026-02-25T17:13:27.560491Z","steps":["trace[47543169] 'read index received' (duration: 358.994205ms)","trace[47543169] 'applied index is now lower than readState.Index' (duration: 184.008007ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:13:27.560877Z","caller":"traceutil/trace.go:171","msg":"trace[596542380] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"651.497278ms","start":"2026-02-25T17:13:26.909363Z","end":"2026-02-25T17:13:27.56086Z","steps":["trace[596542380] 'process raft request' (duration: 650.230689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:27.56096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:26.909344Z","time spent":"651.56905ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:13:27.561147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.294308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:13:27.561224Z","caller":"traceutil/trace.go:171","msg":"trace[1380354792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"377.439781ms","start":"2026-02-25T17:13:27.183766Z","end":"2026-02-25T17:13:27.561206Z","steps":["trace[1380354792] 'agreement among raft nodes before linearized reading' (duration: 377.292058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:27.561269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:27.183701Z","time spent":"377.560263ms","remote":"127.0.0.1:47156","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-02-25T17:13:27.56135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.8662ms","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-02-25T17:13:27.561382Z","caller":"traceutil/trace.go:171","msg":"trace[963626060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"543.898091ms","start":"2026-02-25T17:13:27.017473Z","end":"2026-02-25T17:13:27.561371Z","steps":["trace[963626060] 'agreement among raft nodes before linearized reading' (duration: 543.778998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:27.56137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.852706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:13:27.561408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:27.017415Z","time spent":"543.986073ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T17:13:27.561422Z","caller":"traceutil/trace.go:171","msg":"trace[1873884613] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:718; }","duration":"315.904658ms","start":"2026-02-25T17:13:27.245502Z","end":"2026-02-25T17:13:27.561407Z","steps":["trace[1873884613] 'agreement among raft nodes before linearized reading' (duration: 315.803665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:27.561463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:27.245439Z","time spent":"316.01388ms","remote":"127.0.0.1:47316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:13:30.323791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.410229ms","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-02-25T17:13:30.32384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.601772ms","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-02-25T17:13:30.323912Z","caller":"traceutil/trace.go:171","msg":"trace[662517681] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:723; }","duration":"341.720315ms","start":"2026-02-25T17:13:29.982173Z","end":"2026-02-25T17:13:30.323894Z","steps":["trace[662517681] 'range keys from in-memory index tree' (duration: 341.433119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:30.323964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:29.98215Z","time spent":"341.804537ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T17:13:30.323864Z","caller":"traceutil/trace.go:171","msg":"trace[1520108846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:723; }","duration":"758.503351ms","start":"2026-02-25T17:13:29.565342Z","end":"2026-02-25T17:13:30.323846Z","steps":["trace[1520108846] 'range keys from in-memory index tree' (duration: 758.286766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:30.32403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.615875ms","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-02-25T17:13:30.324083Z","caller":"traceutil/trace.go:171","msg":"trace[596002736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:723; }","duration":"759.689157ms","start":"2026-02-25T17:13:29.564375Z","end":"2026-02-25T17:13:30.324064Z","steps":["trace[596002736] 'range keys from in-memory index tree' (duration: 759.521533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:30.324108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:29.564357Z","time spent":"759.744058ms","remote":"127.0.0.1:47416","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-02-25T17:13:30.324096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:29.565335Z","time spent":"758.725976ms","remote":"127.0.0.1:47416","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-02-25T17:13:30.324262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.048243ms","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-02-25T17:13:30.324296Z","caller":"traceutil/trace.go:171","msg":"trace[1016968806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:723; }","duration":"754.080854ms","start":"2026-02-25T17:13:29.570205Z","end":"2026-02-25T17:13:30.324286Z","steps":["trace[1016968806] 'range keys from in-memory index tree' (duration: 753.966821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:30.324325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:29.570184Z","time spent":"754.134164ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T17:13:30.62423Z","caller":"traceutil/trace.go:171","msg":"trace[1914327493] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"296.615613ms","start":"2026-02-25T17:13:30.327595Z","end":"2026-02-25T17:13:30.624211Z","steps":["trace[1914327493] 'process raft request' (duration: 296.496461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:30.844673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.033517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:13:30.844847Z","caller":"traceutil/trace.go:171","msg":"trace[2002516446] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"515.577118ms","start":"2026-02-25T17:13:30.329256Z","end":"2026-02-25T17:13:30.844833Z","steps":["trace[2002516446] 'process raft request' (duration: 515.539477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:30.844902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:30.329247Z","time spent":"515.628729ms","remote":"127.0.0.1:47416","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-02-25T17:13:30.844866Z","caller":"traceutil/trace.go:171","msg":"trace[935817756] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"516.753134ms","start":"2026-02-25T17:13:30.328075Z","end":"2026-02-25T17:13:30.844829Z","steps":["trace[935817756] 'process raft request' (duration: 322.484782ms)","trace[935817756] 'compare' (duration: 193.915584ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:13:30.845053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:30.328056Z","time spent":"516.899357ms","remote":"127.0.0.1:47416","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-02-25T17:13:30.845098Z","caller":"traceutil/trace.go:171","msg":"trace[1763607627] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"516.930407ms","start":"2026-02-25T17:13:30.32815Z","end":"2026-02-25T17:13:30.845081Z","steps":["trace[1763607627] 'process raft request' (duration: 516.60615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:30.845204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:30.328144Z","time spent":"516.999279ms","remote":"127.0.0.1:47416","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-02-25T17:13:50.922701Z","caller":"traceutil/trace.go:171","msg":"trace[1706963343] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"214.97975ms","start":"2026-02-25T17:13:50.707686Z","end":"2026-02-25T17:13:50.922666Z","steps":["trace[1706963343] 'process raft request' (duration: 214.725484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:51.59021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.251624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:13:51.590521Z","caller":"traceutil/trace.go:171","msg":"trace[1041393252] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"644.10188ms","start":"2026-02-25T17:13:50.946381Z","end":"2026-02-25T17:13:51.590482Z","steps":["trace[1041393252] 'process raft request' (duration: 351.504537ms)","trace[1041393252] 'compare' (duration: 292.07152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:13:51.59075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:50.946364Z","time spent":"644.270644ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:13:51.5908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.757622ms","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-02-25T17:13:51.590819Z","caller":"traceutil/trace.go:171","msg":"trace[255760191] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"643.281451ms","start":"2026-02-25T17:13:50.947024Z","end":"2026-02-25T17:13:51.590306Z","steps":["trace[255760191] 'read index received' (duration: 350.77212ms)","trace[255760191] 'applied index is now lower than readState.Index' (duration: 292.507691ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:13:51.590881Z","caller":"traceutil/trace.go:171","msg":"trace[896018058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"643.886575ms","start":"2026-02-25T17:13:50.946976Z","end":"2026-02-25T17:13:51.590863Z","steps":["trace[896018058] 'agreement among raft nodes before linearized reading' (duration: 643.632199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:51.59093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:50.946955Z","time spent":"643.965457ms","remote":"127.0.0.1:47416","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-02-25T17:13:51.591042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.528289ms","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-02-25T17:13:51.591088Z","caller":"traceutil/trace.go:171","msg":"trace[640455150] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"640.58638ms","start":"2026-02-25T17:13:50.950485Z","end":"2026-02-25T17:13:51.591071Z","steps":["trace[640455150] 'agreement among raft nodes before linearized reading' (duration: 640.444726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:51.59112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:50.950478Z","time spent":"640.633621ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T17:13:51.591121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.295935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:13:51.591169Z","caller":"traceutil/trace.go:171","msg":"trace[1746775090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"409.358996ms","start":"2026-02-25T17:13:51.181796Z","end":"2026-02-25T17:13:51.591155Z","steps":["trace[1746775090] 'agreement among raft nodes before linearized reading' (duration: 409.281844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:51.591206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:51.181784Z","time spent":"409.415827ms","remote":"127.0.0.1:47156","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-02-25T17:13:51.591228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.904275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:13:51.591268Z","caller":"traceutil/trace.go:171","msg":"trace[502114342] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:788; }","duration":"428.969267ms","start":"2026-02-25T17:13:51.16229Z","end":"2026-02-25T17:13:51.591259Z","steps":["trace[502114342] 'agreement among raft nodes before linearized reading' (duration: 428.901285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:51.591303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:51.162273Z","time spent":"429.023108ms","remote":"127.0.0.1:47488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-25T17:13:51.786497Z","caller":"traceutil/trace.go:171","msg":"trace[1987893517] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"190.911128ms","start":"2026-02-25T17:13:51.59556Z","end":"2026-02-25T17:13:51.786471Z","steps":["trace[1987893517] 'process raft request' (duration: 134.982073ms)","trace[1987893517] 'compare' (duration: 55.816372ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:13:51.927648Z","caller":"traceutil/trace.go:171","msg":"trace[1601377272] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"331.518797ms","start":"2026-02-25T17:13:51.596084Z","end":"2026-02-25T17:13:51.927603Z","steps":["trace[1601377272] 'process raft request' (duration: 331.359093ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:13:51.927874Z","caller":"traceutil/trace.go:171","msg":"trace[1144816090] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"331.554698ms","start":"2026-02-25T17:13:51.5963Z","end":"2026-02-25T17:13:51.927855Z","steps":["trace[1144816090] 'process raft request' (duration: 331.265101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:51.927985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.662848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:13:51.927846Z","caller":"traceutil/trace.go:171","msg":"trace[187993019] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:821; }","duration":"132.497424ms","start":"2026-02-25T17:13:51.795313Z","end":"2026-02-25T17:13:51.92781Z","steps":["trace[187993019] 'read index received' (duration: 132.195657ms)","trace[187993019] 'applied index is now lower than readState.Index' (duration: 299.417µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:13:51.928035Z","caller":"traceutil/trace.go:171","msg":"trace[118655338] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:791; }","duration":"132.799231ms","start":"2026-02-25T17:13:51.795226Z","end":"2026-02-25T17:13:51.928026Z","steps":["trace[118655338] 'agreement among raft nodes before linearized reading' (duration: 132.687768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:51.928048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:51.596067Z","time spent":"331.6544ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:13:51.928112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:13:51.59629Z","time spent":"331.637949ms","remote":"127.0.0.1:47416","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-02-25T17:13:53.074965Z","caller":"traceutil/trace.go:171","msg":"trace[254435926] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"142.429243ms","start":"2026-02-25T17:13:52.932516Z","end":"2026-02-25T17:13:53.074945Z","steps":["trace[254435926] 'process raft request' (duration: 142.315469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:53.340313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.74543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:13:53.340395Z","caller":"traceutil/trace.go:171","msg":"trace[2085892181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"159.888734ms","start":"2026-02-25T17:13:53.180487Z","end":"2026-02-25T17:13:53.340376Z","steps":["trace[2085892181] 'range keys from in-memory index tree' (duration: 159.618968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:13:53.901204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.498815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T17:13:53.901251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.818783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:13:53.901285Z","caller":"traceutil/trace.go:171","msg":"trace[1143718938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"109.657949ms","start":"2026-02-25T17:13:53.791608Z","end":"2026-02-25T17:13:53.901266Z","steps":["trace[1143718938] 'range keys from in-memory index tree' (duration: 109.282861ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:13:53.901342Z","caller":"traceutil/trace.go:171","msg":"trace[887103431] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:794; }","duration":"110.001708ms","start":"2026-02-25T17:13:53.791321Z","end":"2026-02-25T17:13:53.901323Z","steps":["trace[887103431] 'count revisions from in-memory index tree' (duration: 109.631348ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:05.23555Z","caller":"traceutil/trace.go:171","msg":"trace[1352247907] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"107.541881ms","start":"2026-02-25T17:14:05.127976Z","end":"2026-02-25T17:14:05.235518Z","steps":["trace[1352247907] 'process raft request' (duration: 107.311386ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:07.980062Z","caller":"traceutil/trace.go:171","msg":"trace[666742414] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"253.635837ms","start":"2026-02-25T17:14:07.726401Z","end":"2026-02-25T17:14:07.980037Z","steps":["trace[666742414] 'process raft request' (duration: 253.479763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:08.535492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697515742305169,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:14:08.903681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.725297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:14:08.903791Z","caller":"traceutil/trace.go:171","msg":"trace[646261737] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"869.159221ms","start":"2026-02-25T17:14:08.034611Z","end":"2026-02-25T17:14:08.90377Z","steps":["trace[646261737] 'read index received' (duration: 509.210509ms)","trace[646261737] 'applied index is now lower than readState.Index' (duration: 359.945962ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:14:08.903928Z","caller":"traceutil/trace.go:171","msg":"trace[1678789383] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"916.493328ms","start":"2026-02-25T17:14:07.987401Z","end":"2026-02-25T17:14:08.903895Z","steps":["trace[1678789383] 'process raft request' (duration: 556.480265ms)","trace[1678789383] 'compare' (duration: 359.304487ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:14:08.904128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:07.987381Z","time spent":"916.635362ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:14:08.904222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.605212ms","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-02-25T17:14:08.904297Z","caller":"traceutil/trace.go:171","msg":"trace[1503428483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:885; }","duration":"869.719993ms","start":"2026-02-25T17:14:08.03456Z","end":"2026-02-25T17:14:08.90428Z","steps":["trace[1503428483] 'agreement among raft nodes before linearized reading' (duration: 869.496848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:08.904425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:08.034541Z","time spent":"869.869407ms","remote":"127.0.0.1:47416","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-02-25T17:14:08.904412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.886659ms","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-02-25T17:14:08.904488Z","caller":"traceutil/trace.go:171","msg":"trace[470301309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:885; }","duration":"866.973301ms","start":"2026-02-25T17:14:08.037501Z","end":"2026-02-25T17:14:08.904474Z","steps":["trace[470301309] 'agreement among raft nodes before linearized reading' (duration: 866.613532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:08.904599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:08.037495Z","time spent":"867.077223ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T17:14:09.234743Z","caller":"traceutil/trace.go:171","msg":"trace[1494380917] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:922; }","duration":"200.808485ms","start":"2026-02-25T17:14:09.033901Z","end":"2026-02-25T17:14:09.23471Z","steps":["trace[1494380917] 'read index received' (duration: 200.520838ms)","trace[1494380917] 'applied index is now lower than readState.Index' (duration: 286.767µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:14:09.234871Z","caller":"traceutil/trace.go:171","msg":"trace[569414107] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"326.240236ms","start":"2026-02-25T17:14:08.908577Z","end":"2026-02-25T17:14:09.234817Z","steps":["trace[569414107] 'process raft request' (duration: 325.933559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:09.235067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.154302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T17:14:09.235167Z","caller":"traceutil/trace.go:171","msg":"trace[1560582009] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:887; }","duration":"201.298725ms","start":"2026-02-25T17:14:09.033842Z","end":"2026-02-25T17:14:09.235141Z","steps":["trace[1560582009] 'agreement among raft nodes before linearized reading' (duration: 201.06237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:09.23517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:08.908554Z","time spent":"326.473691ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:14:21.067947Z","caller":"traceutil/trace.go:171","msg":"trace[877491838] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"105.434553ms","start":"2026-02-25T17:14:20.962481Z","end":"2026-02-25T17:14:21.067916Z","steps":["trace[877491838] 'process raft request' (duration: 104.769948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:21.351567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.311542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:14:21.351677Z","caller":"traceutil/trace.go:171","msg":"trace[578588767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"170.466446ms","start":"2026-02-25T17:14:21.181187Z","end":"2026-02-25T17:14:21.351653Z","steps":["trace[578588767] 'range keys from in-memory index tree' (duration: 170.222951ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:25.782944Z","caller":"traceutil/trace.go:171","msg":"trace[241196535] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"344.23238ms","start":"2026-02-25T17:14:25.438692Z","end":"2026-02-25T17:14:25.782924Z","steps":["trace[241196535] 'read index received' (duration: 344.041205ms)","trace[241196535] 'applied index is now lower than readState.Index' (duration: 190.225µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:14:25.783146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.444434ms","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-02-25T17:14:25.78318Z","caller":"traceutil/trace.go:171","msg":"trace[713879481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:953; }","duration":"344.529166ms","start":"2026-02-25T17:14:25.438639Z","end":"2026-02-25T17:14:25.783168Z","steps":["trace[713879481] 'agreement among raft nodes before linearized reading' (duration: 344.376283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:25.783212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:25.438556Z","time spent":"344.648789ms","remote":"127.0.0.1:47416","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-02-25T17:14:25.783269Z","caller":"traceutil/trace.go:171","msg":"trace[245759162] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"372.214023ms","start":"2026-02-25T17:14:25.411026Z","end":"2026-02-25T17:14:25.78324Z","steps":["trace[245759162] 'process raft request' (duration: 371.765503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:25.783421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:25.410999Z","time spent":"372.340525ms","remote":"127.0.0.1:47416","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-02-25T17:14:26.223803Z","caller":"traceutil/trace.go:171","msg":"trace[244389926] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"144.871309ms","start":"2026-02-25T17:14:26.078906Z","end":"2026-02-25T17:14:26.223778Z","steps":["trace[244389926] 'read index received' (duration: 107.156452ms)","trace[244389926] 'applied index is now lower than readState.Index' (duration: 37.713447ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:14:26.223902Z","caller":"traceutil/trace.go:171","msg":"trace[1213515270] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"436.52394ms","start":"2026-02-25T17:14:25.787354Z","end":"2026-02-25T17:14:26.223878Z","steps":["trace[1213515270] 'process raft request' (duration: 398.779543ms)","trace[1213515270] 'compare' (duration: 37.326727ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:14:26.224052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:25.787328Z","time spent":"436.648174ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:14:26.224061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.146605ms","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-02-25T17:14:26.224247Z","caller":"traceutil/trace.go:171","msg":"trace[870301260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"145.37761ms","start":"2026-02-25T17:14:26.078856Z","end":"2026-02-25T17:14:26.224234Z","steps":["trace[870301260] 'agreement among raft nodes before linearized reading' (duration: 145.057403ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:26.53484Z","caller":"traceutil/trace.go:171","msg":"trace[184308285] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"305.58712ms","start":"2026-02-25T17:14:26.229235Z","end":"2026-02-25T17:14:26.534822Z","steps":["trace[184308285] 'process raft request' (duration: 305.457557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:26.534984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:26.229222Z","time spent":"305.686673ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:14:28.126732Z","caller":"traceutil/trace.go:171","msg":"trace[2046742997] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"335.741675ms","start":"2026-02-25T17:14:27.790973Z","end":"2026-02-25T17:14:28.126715Z","steps":["trace[2046742997] 'process raft request' (duration: 335.634842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:28.126847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:27.790955Z","time spent":"335.831306ms","remote":"127.0.0.1:47416","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-02-25T17:14:28.657701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.798609ms","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-02-25T17:14:28.657703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.656974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:14:28.657746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.031464ms","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-02-25T17:14:28.657777Z","caller":"traceutil/trace.go:171","msg":"trace[863270995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:958; }","duration":"118.925742ms","start":"2026-02-25T17:14:28.538833Z","end":"2026-02-25T17:14:28.657759Z","steps":["trace[863270995] 'range keys from in-memory index tree' (duration: 118.546554ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:28.657804Z","caller":"traceutil/trace.go:171","msg":"trace[604581736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:958; }","duration":"428.100815ms","start":"2026-02-25T17:14:28.229687Z","end":"2026-02-25T17:14:28.657788Z","steps":["trace[604581736] 'range keys from in-memory index tree' (duration: 427.770547ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:28.6578Z","caller":"traceutil/trace.go:171","msg":"trace[1248122305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"477.802667ms","start":"2026-02-25T17:14:28.179979Z","end":"2026-02-25T17:14:28.657781Z","steps":["trace[1248122305] 'range keys from in-memory index tree' (duration: 477.554582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:28.657838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:28.229633Z","time spent":"428.192987ms","remote":"127.0.0.1:47416","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-02-25T17:14:28.65788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:28.179957Z","time spent":"477.908ms","remote":"127.0.0.1:47156","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-02-25T17:14:28.947791Z","caller":"traceutil/trace.go:171","msg":"trace[919235104] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"286.579505ms","start":"2026-02-25T17:14:28.661191Z","end":"2026-02-25T17:14:28.94777Z","steps":["trace[919235104] 'process raft request' (duration: 286.439721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:29.386208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.342766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:14:29.386319Z","caller":"traceutil/trace.go:171","msg":"trace[1725097496] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"352.013569ms","start":"2026-02-25T17:14:29.034288Z","end":"2026-02-25T17:14:29.386302Z","steps":["trace[1725097496] 'read index received' (duration: 103.58504ms)","trace[1725097496] 'applied index is now lower than readState.Index' (duration: 248.427229ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:14:29.386409Z","caller":"traceutil/trace.go:171","msg":"trace[912670379] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"724.247682ms","start":"2026-02-25T17:14:28.66215Z","end":"2026-02-25T17:14:29.386398Z","steps":["trace[912670379] 'process raft request' (duration: 475.631359ms)","trace[912670379] 'compare' (duration: 248.217013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:14:29.386473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:28.662144Z","time spent":"724.298633ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:14:29.386653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.701053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T17:14:29.386639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.337765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:14:29.3867Z","caller":"traceutil/trace.go:171","msg":"trace[1856691431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"261.803657ms","start":"2026-02-25T17:14:29.124885Z","end":"2026-02-25T17:14:29.386689Z","steps":["trace[1856691431] 'agreement among raft nodes before linearized reading' (duration: 261.634573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:29.386692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.404007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T17:14:29.38672Z","caller":"traceutil/trace.go:171","msg":"trace[736298005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"204.456388ms","start":"2026-02-25T17:14:29.182244Z","end":"2026-02-25T17:14:29.386701Z","steps":["trace[736298005] 'agreement among raft nodes before linearized reading' (duration: 204.312155ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:29.386736Z","caller":"traceutil/trace.go:171","msg":"trace[1342138429] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:960; }","duration":"352.485819ms","start":"2026-02-25T17:14:29.034238Z","end":"2026-02-25T17:14:29.386723Z","steps":["trace[1342138429] 'agreement among raft nodes before linearized reading' (duration: 352.305915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:29.386745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.421226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-25T17:14:29.386783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:29.034218Z","time spent":"352.557321ms","remote":"127.0.0.1:47198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.240\" "} {"level":"info","ts":"2026-02-25T17:14:29.386812Z","caller":"traceutil/trace.go:171","msg":"trace[1190436511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:960; }","duration":"187.537029ms","start":"2026-02-25T17:14:29.199259Z","end":"2026-02-25T17:14:29.386796Z","steps":["trace[1190436511] 'agreement among raft nodes before linearized reading' (duration: 187.295053ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:29.860754Z","caller":"traceutil/trace.go:171","msg":"trace[1634313905] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"470.154594ms","start":"2026-02-25T17:14:29.390572Z","end":"2026-02-25T17:14:29.860726Z","steps":["trace[1634313905] 'process raft request' (duration: 373.616625ms)","trace[1634313905] 'compare' (duration: 95.758681ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:14:29.861109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:29.390554Z","time spent":"470.269126ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:14:29.86166Z","caller":"traceutil/trace.go:171","msg":"trace[605357316] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"413.576393ms","start":"2026-02-25T17:14:29.448072Z","end":"2026-02-25T17:14:29.861648Z","steps":["trace[605357316] 'process raft request' (duration: 413.536502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:29.861873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:29.448046Z","time spent":"413.656914ms","remote":"127.0.0.1:47198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:14:29.86188Z","caller":"traceutil/trace.go:171","msg":"trace[1862709220] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"470.175173ms","start":"2026-02-25T17:14:29.39168Z","end":"2026-02-25T17:14:29.861855Z","steps":["trace[1862709220] 'process raft request' (duration: 468.990937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:29.862108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:29.39166Z","time spent":"470.371288ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:14:30.380524Z","caller":"traceutil/trace.go:171","msg":"trace[68698136] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"250.646729ms","start":"2026-02-25T17:14:30.12985Z","end":"2026-02-25T17:14:30.380496Z","steps":["trace[68698136] 'read index received' (duration: 234.356496ms)","trace[68698136] 'applied index is now lower than readState.Index' (duration: 16.286763ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:14:30.380574Z","caller":"traceutil/trace.go:171","msg":"trace[810770458] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"267.312892ms","start":"2026-02-25T17:14:30.113226Z","end":"2026-02-25T17:14:30.380539Z","steps":["trace[810770458] 'process raft request' (duration: 251.041109ms)","trace[810770458] 'compare' (duration: 16.108579ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:14:30.380821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.237876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:14:30.380869Z","caller":"traceutil/trace.go:171","msg":"trace[606963898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"198.354378ms","start":"2026-02-25T17:14:30.182504Z","end":"2026-02-25T17:14:30.380858Z","steps":["trace[606963898] 'agreement among raft nodes before linearized reading' (duration: 198.263495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:30.380822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.954067ms","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-02-25T17:14:30.381046Z","caller":"traceutil/trace.go:171","msg":"trace[1452263871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:964; }","duration":"251.233113ms","start":"2026-02-25T17:14:30.129804Z","end":"2026-02-25T17:14:30.381037Z","steps":["trace[1452263871] 'agreement among raft nodes before linearized reading' (duration: 250.863704ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:30.99642Z","caller":"traceutil/trace.go:171","msg":"trace[1487827579] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"315.1245ms","start":"2026-02-25T17:14:30.681258Z","end":"2026-02-25T17:14:30.996383Z","steps":["trace[1487827579] 'process raft request' (duration: 314.953056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:30.996557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:30.681233Z","time spent":"315.258503ms","remote":"127.0.0.1:47248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:14:36.894563Z","caller":"traceutil/trace.go:171","msg":"trace[1535723980] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"422.391854ms","start":"2026-02-25T17:14:36.472144Z","end":"2026-02-25T17:14:36.894536Z","steps":["trace[1535723980] 'process raft request' (duration: 422.059977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:36.89473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:36.472115Z","time spent":"422.541557ms","remote":"127.0.0.1:47416","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-02-25T17:14:37.264265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.398561ms","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-02-25T17:14:37.264327Z","caller":"traceutil/trace.go:171","msg":"trace[789391103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:997; }","duration":"219.519104ms","start":"2026-02-25T17:14:37.044794Z","end":"2026-02-25T17:14:37.264313Z","steps":["trace[789391103] 'range keys from in-memory index tree' (duration: 219.219447ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:37.819291Z","caller":"traceutil/trace.go:171","msg":"trace[1282736101] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"399.585861ms","start":"2026-02-25T17:14:37.419681Z","end":"2026-02-25T17:14:37.819267Z","steps":["trace[1282736101] 'read index received' (duration: 399.352595ms)","trace[1282736101] 'applied index is now lower than readState.Index' (duration: 232.156µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:14:37.819331Z","caller":"traceutil/trace.go:171","msg":"trace[1327047039] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"550.600611ms","start":"2026-02-25T17:14:37.268709Z","end":"2026-02-25T17:14:37.819309Z","steps":["trace[1327047039] 'process raft request' (duration: 550.420287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:37.819464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:37.268698Z","time spent":"550.690852ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:14:37.81949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.809566ms","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-02-25T17:14:37.81952Z","caller":"traceutil/trace.go:171","msg":"trace[2122934063] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:998; }","duration":"399.886458ms","start":"2026-02-25T17:14:37.419625Z","end":"2026-02-25T17:14:37.819511Z","steps":["trace[2122934063] 'agreement among raft nodes before linearized reading' (duration: 399.734355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:14:37.819558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:14:37.419602Z","time spent":"399.948899ms","remote":"127.0.0.1:47416","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-02-25T17:14:37.972144Z","caller":"traceutil/trace.go:171","msg":"trace[1629448289] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"148.569255ms","start":"2026-02-25T17:14:37.823547Z","end":"2026-02-25T17:14:37.972117Z","steps":["trace[1629448289] 'process raft request' (duration: 144.341418ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:40.109622Z","caller":"traceutil/trace.go:171","msg":"trace[44540231] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"106.748403ms","start":"2026-02-25T17:14:40.00284Z","end":"2026-02-25T17:14:40.109588Z","steps":["trace[44540231] 'process raft request' (duration: 106.072848ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:40.111559Z","caller":"traceutil/trace.go:171","msg":"trace[1213011363] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"108.572495ms","start":"2026-02-25T17:14:40.002957Z","end":"2026-02-25T17:14:40.111529Z","steps":["trace[1213011363] 'process raft request' (duration: 108.360841ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:40.111694Z","caller":"traceutil/trace.go:171","msg":"trace[1839981330] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"108.077702ms","start":"2026-02-25T17:14:40.003594Z","end":"2026-02-25T17:14:40.111672Z","steps":["trace[1839981330] 'process raft request' (duration: 107.884208ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:41.01964Z","caller":"traceutil/trace.go:171","msg":"trace[1084316677] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"107.404247ms","start":"2026-02-25T17:14:40.912193Z","end":"2026-02-25T17:14:41.019598Z","steps":["trace[1084316677] 'process raft request' (duration: 107.233883ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:14:42.253221Z","caller":"traceutil/trace.go:171","msg":"trace[241647619] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"133.629002ms","start":"2026-02-25T17:14:42.119557Z","end":"2026-02-25T17:14:42.253186Z","steps":["trace[241647619] 'process raft request' (duration: 94.877231ms)","trace[241647619] 'compare' (duration: 38.555875ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:14:42.253269Z","caller":"traceutil/trace.go:171","msg":"trace[32816070] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"133.209072ms","start":"2026-02-25T17:14:42.120034Z","end":"2026-02-25T17:14:42.253243Z","steps":["trace[32816070] 'process raft request' (duration: 133.079429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:17:49.304797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.744033ms","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-02-25T17:17:49.304866Z","caller":"traceutil/trace.go:171","msg":"trace[1477115858] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1826; }","duration":"186.857245ms","start":"2026-02-25T17:17:49.117995Z","end":"2026-02-25T17:17:49.304852Z","steps":["trace[1477115858] 'range keys from in-memory index tree' (duration: 186.6418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:17:49.305121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.172526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:17:49.30523Z","caller":"traceutil/trace.go:171","msg":"trace[1063273030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1826; }","duration":"122.33053ms","start":"2026-02-25T17:17:49.182878Z","end":"2026-02-25T17:17:49.305208Z","steps":["trace[1063273030] 'range keys from in-memory index tree' (duration: 122.098164ms)"],"step_count":1}