{"level":"warn","ts":"2026-02-11T09:14:51.922373Z","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-11T09:14:51.9226Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.99: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.99:2380","--initial-cluster=instance=https://199.204.45.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.99: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-11T09:14:51.922723Z","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-11T09:14:51.92277Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-11T09:14:51.922863Z","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-11T09:14:51.923606Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"]} {"level":"info","ts":"2026-02-11T09:14:51.923807Z","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":2,"max-cpu-available":2,"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.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.99: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-11T09:14:51.930897Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.134464ms"} {"level":"info","ts":"2026-02-11T09:14:51.942103Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"21032583b6cd9182","cluster-id":"a96cbe7391f279b7"} {"level":"info","ts":"2026-02-11T09:14:51.942244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T09:14:51.942312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 0"} {"level":"info","ts":"2026-02-11T09:14:51.942383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21032583b6cd9182 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T09:14:51.942465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 1"} {"level":"info","ts":"2026-02-11T09:14:51.942539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"warn","ts":"2026-02-11T09:14:51.956339Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T09:14:51.960786Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T09:14:51.96499Z","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-11T09:14:51.976201Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"21032583b6cd9182","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T09:14:51.976657Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"21032583b6cd9182","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T09:14:51.976848Z","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-11T09:14:51.976971Z","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-11T09:14:51.977059Z","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-11T09:14:51.977405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"info","ts":"2026-02-11T09:14:51.97761Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","added-peer-id":"21032583b6cd9182","added-peer-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-11T09:14:51.985926Z","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-11T09:14:51.98619Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"21032583b6cd9182","initial-advertise-peer-urls":["https://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T09:14:51.986003Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-11T09:14:51.988279Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-11T09:14:51.988311Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T09:14:52.242763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T09:14:52.243594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T09:14:52.243698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgPreVoteResp from 21032583b6cd9182 at term 1"} {"level":"info","ts":"2026-02-11T09:14:52.243769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became candidate at term 2"} {"level":"info","ts":"2026-02-11T09:14:52.24381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgVoteResp from 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-11T09:14:52.243846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became leader at term 2"} {"level":"info","ts":"2026-02-11T09:14:52.243899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21032583b6cd9182 elected leader 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-11T09:14:52.245226Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"21032583b6cd9182","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.99:2379]}","request-path":"/0/members/21032583b6cd9182/attributes","cluster-id":"a96cbe7391f279b7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T09:14:52.245353Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T09:14:52.248268Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T09:14:52.24542Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T09:14:52.249446Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T09:14:52.249483Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T09:14:52.278367Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T09:14:52.282958Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T09:14:52.283471Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T09:14:52.288198Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T09:14:52.303369Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.99:2379"} {"level":"info","ts":"2026-02-11T09:14:54.275218Z","caller":"traceutil/trace.go:171","msg":"trace[1888344918] linearizableReadLoop","detail":"{readStateIndex:31; appliedIndex:30; }","duration":"100.04703ms","start":"2026-02-11T09:14:54.175145Z","end":"2026-02-11T09:14:54.275192Z","steps":["trace[1888344918] 'read index received' (duration: 53.104672ms)","trace[1888344918] 'applied index is now lower than readState.Index' (duration: 46.941418ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:14:54.275813Z","caller":"traceutil/trace.go:171","msg":"trace[368205856] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"102.090604ms","start":"2026-02-11T09:14:54.173707Z","end":"2026-02-11T09:14:54.275798Z","steps":["trace[368205856] 'process raft request' (duration: 54.593794ms)","trace[368205856] 'compare' (duration: 46.763445ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:14:54.277071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.879221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-11T09:14:54.277196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.017274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3178"} {"level":"info","ts":"2026-02-11T09:14:54.279412Z","caller":"traceutil/trace.go:171","msg":"trace[1795629919] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:28; }","duration":"104.235462ms","start":"2026-02-11T09:14:54.175163Z","end":"2026-02-11T09:14:54.279398Z","steps":["trace[1795629919] 'agreement among raft nodes before linearized reading' (duration: 101.994913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:14:54.277521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.32ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T09:14:54.279722Z","caller":"traceutil/trace.go:171","msg":"trace[1913893638] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:28; }","duration":"104.495099ms","start":"2026-02-11T09:14:54.175181Z","end":"2026-02-11T09:14:54.279676Z","steps":["trace[1913893638] 'agreement among raft nodes before linearized reading' (duration: 102.308711ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:14:54.27922Z","caller":"traceutil/trace.go:171","msg":"trace[1498905542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28; }","duration":"104.058118ms","start":"2026-02-11T09:14:54.175144Z","end":"2026-02-11T09:14:54.279202Z","steps":["trace[1498905542] 'agreement among raft nodes before linearized reading' (duration: 101.736928ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:14:57.567936Z","caller":"traceutil/trace.go:171","msg":"trace[430548695] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"866.546659ms","start":"2026-02-11T09:14:56.701371Z","end":"2026-02-11T09:14:57.567918Z","steps":["trace[430548695] 'process raft request' (duration: 866.12632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:14:57.570127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:14:56.701347Z","time spent":"866.653521ms","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3690,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T09:15:15.822659Z","caller":"traceutil/trace.go:171","msg":"trace[1289044860] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"137.593681ms","start":"2026-02-11T09:15:15.685049Z","end":"2026-02-11T09:15:15.822643Z","steps":["trace[1289044860] 'process raft request' (duration: 137.480939ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:24.188436Z","caller":"traceutil/trace.go:171","msg":"trace[1285702071] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"215.016344ms","start":"2026-02-11T09:15:23.973398Z","end":"2026-02-11T09:15:24.188415Z","steps":["trace[1285702071] 'read index received' (duration: 214.8508ms)","trace[1285702071] 'applied index is now lower than readState.Index' (duration: 164.294µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:15:24.188587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.163407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T09:15:24.188628Z","caller":"traceutil/trace.go:171","msg":"trace[1745063452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"215.234649ms","start":"2026-02-11T09:15:23.973381Z","end":"2026-02-11T09:15:24.188616Z","steps":["trace[1745063452] 'agreement among raft nodes before linearized reading' (duration: 215.125717ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:24.188757Z","caller":"traceutil/trace.go:171","msg":"trace[1556226559] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"251.414264ms","start":"2026-02-11T09:15:23.937334Z","end":"2026-02-11T09:15:24.188748Z","steps":["trace[1556226559] 'process raft request' (duration: 250.936604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:26.023353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.451897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T09:15:26.023415Z","caller":"traceutil/trace.go:171","msg":"trace[1260822746] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"100.850704ms","start":"2026-02-11T09:15:25.922554Z","end":"2026-02-11T09:15:26.023405Z","steps":["trace[1260822746] 'read index received' (duration: 32.621µs)","trace[1260822746] 'applied index is now lower than readState.Index' (duration: 100.817323ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:15:26.023477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.921706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:15:26.023503Z","caller":"traceutil/trace.go:171","msg":"trace[1179203917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"100.972256ms","start":"2026-02-11T09:15:25.922527Z","end":"2026-02-11T09:15:26.023499Z","steps":["trace[1179203917] 'agreement among raft nodes before linearized reading' (duration: 100.904175ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:26.023612Z","caller":"traceutil/trace.go:171","msg":"trace[1550923995] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"311.46546ms","start":"2026-02-11T09:15:25.712141Z","end":"2026-02-11T09:15:26.023607Z","steps":["trace[1550923995] 'process raft request' (duration: 200.625954ms)","trace[1550923995] 'compare' (duration: 110.273623ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:15:26.023641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:25.712117Z","time spent":"311.50873ms","remote":"127.0.0.1:48374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T09:15:26.309279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.112999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-11T09:15:26.30936Z","caller":"traceutil/trace.go:171","msg":"trace[473821243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"114.243932ms","start":"2026-02-11T09:15:26.195098Z","end":"2026-02-11T09:15:26.309342Z","steps":["trace[473821243] 'range keys from in-memory index tree' (duration: 113.943596ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:26.681927Z","caller":"traceutil/trace.go:171","msg":"trace[877914238] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"320.683154ms","start":"2026-02-11T09:15:26.361226Z","end":"2026-02-11T09:15:26.681909Z","steps":["trace[877914238] 'read index received' (duration: 272.344559ms)","trace[877914238] 'applied index is now lower than readState.Index' (duration: 48.337845ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:15:26.682159Z","caller":"traceutil/trace.go:171","msg":"trace[382119989] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"367.709971ms","start":"2026-02-11T09:15:26.31444Z","end":"2026-02-11T09:15:26.68215Z","steps":["trace[382119989] 'process raft request' (duration: 319.124331ms)","trace[382119989] 'compare' (duration: 48.267963ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:15:26.682233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:26.314432Z","time spent":"367.753692ms","remote":"127.0.0.1:48582","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-11T09:15:26.682406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.177325ms","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-11T09:15:26.682443Z","caller":"traceutil/trace.go:171","msg":"trace[555153284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"321.228466ms","start":"2026-02-11T09:15:26.361198Z","end":"2026-02-11T09:15:26.682427Z","steps":["trace[555153284] 'agreement among raft nodes before linearized reading' (duration: 321.149014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:26.682467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:26.361185Z","time spent":"321.277477ms","remote":"127.0.0.1:48582","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-11T09:15:26.682581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.797293ms","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-11T09:15:26.682602Z","caller":"traceutil/trace.go:171","msg":"trace[518411539] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"283.840455ms","start":"2026-02-11T09:15:26.398757Z","end":"2026-02-11T09:15:26.682597Z","steps":["trace[518411539] 'agreement among raft nodes before linearized reading' (duration: 283.784774ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:28.51883Z","caller":"traceutil/trace.go:171","msg":"trace[1143721658] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"153.369232ms","start":"2026-02-11T09:15:28.365446Z","end":"2026-02-11T09:15:28.518815Z","steps":["trace[1143721658] 'process raft request' (duration: 153.242509ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:31.889432Z","caller":"traceutil/trace.go:171","msg":"trace[71429096] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"536.68611ms","start":"2026-02-11T09:15:31.352348Z","end":"2026-02-11T09:15:31.889034Z","steps":["trace[71429096] 'process raft request' (duration: 536.456815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:31.889594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:31.352331Z","time spent":"537.195551ms","remote":"127.0.0.1:48526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T09:15:33.529065Z","caller":"traceutil/trace.go:171","msg":"trace[319723158] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"173.862598ms","start":"2026-02-11T09:15:33.355184Z","end":"2026-02-11T09:15:33.529047Z","steps":["trace[319723158] 'process raft request' (duration: 173.737046ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:33.532413Z","caller":"traceutil/trace.go:171","msg":"trace[1244366407] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"162.384312ms","start":"2026-02-11T09:15:33.370011Z","end":"2026-02-11T09:15:33.532395Z","steps":["trace[1244366407] 'process raft request' (duration: 162.286939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:36.685415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.273394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T09:15:36.685563Z","caller":"traceutil/trace.go:171","msg":"trace[1586781813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"124.51581ms","start":"2026-02-11T09:15:36.561007Z","end":"2026-02-11T09:15:36.685523Z","steps":["trace[1586781813] 'range keys from in-memory index tree' (duration: 123.823504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:44.535789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.102341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T09:15:44.535888Z","caller":"traceutil/trace.go:171","msg":"trace[273062009] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"597.035613ms","start":"2026-02-11T09:15:43.938836Z","end":"2026-02-11T09:15:44.535872Z","steps":["trace[273062009] 'read index received' (duration: 103.732418ms)","trace[273062009] 'applied index is now lower than readState.Index' (duration: 493.301935ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:15:44.536213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.368861ms","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-11T09:15:44.536287Z","caller":"traceutil/trace.go:171","msg":"trace[569262960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:662; }","duration":"597.469283ms","start":"2026-02-11T09:15:43.938807Z","end":"2026-02-11T09:15:44.536276Z","steps":["trace[569262960] 'agreement among raft nodes before linearized reading' (duration: 597.107034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:44.536424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:43.938795Z","time spent":"597.613757ms","remote":"127.0.0.1:48582","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-11T09:15:44.536811Z","caller":"traceutil/trace.go:171","msg":"trace[749015049] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"625.054616ms","start":"2026-02-11T09:15:43.911741Z","end":"2026-02-11T09:15:44.536796Z","steps":["trace[749015049] 'process raft request' (duration: 130.889201ms)","trace[749015049] 'compare' (duration: 492.60771ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:15:44.536888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:43.911725Z","time spent":"625.134449ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T09:15:44.88686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.298566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T09:15:44.887327Z","caller":"traceutil/trace.go:171","msg":"trace[1960786173] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"346.06193ms","start":"2026-02-11T09:15:44.541245Z","end":"2026-02-11T09:15:44.887307Z","steps":["trace[1960786173] 'process raft request' (duration: 194.251372ms)","trace[1960786173] 'compare' (duration: 151.178934ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:15:44.887407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:44.54123Z","time spent":"346.146241ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T09:15:44.907119Z","caller":"traceutil/trace.go:171","msg":"trace[2137489476] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"364.143172ms","start":"2026-02-11T09:15:44.542963Z","end":"2026-02-11T09:15:44.907106Z","steps":["trace[2137489476] 'process raft request' (duration: 363.835205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:44.907213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:44.542955Z","time spent":"364.204773ms","remote":"127.0.0.1:48582","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-11T09:15:44.906903Z","caller":"traceutil/trace.go:171","msg":"trace[996142948] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"144.548756ms","start":"2026-02-11T09:15:44.762339Z","end":"2026-02-11T09:15:44.906887Z","steps":["trace[996142948] 'read index received' (duration: 144.339712ms)","trace[996142948] 'applied index is now lower than readState.Index' (duration: 208.224µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:15:44.907644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.299323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T09:15:44.907681Z","caller":"traceutil/trace.go:171","msg":"trace[2065069155] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:664; }","duration":"145.336563ms","start":"2026-02-11T09:15:44.762334Z","end":"2026-02-11T09:15:44.907671Z","steps":["trace[2065069155] 'agreement among raft nodes before linearized reading' (duration: 145.217181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:44.907922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.167189ms","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-11T09:15:44.90796Z","caller":"traceutil/trace.go:171","msg":"trace[1193306868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:664; }","duration":"118.240741ms","start":"2026-02-11T09:15:44.789709Z","end":"2026-02-11T09:15:44.90795Z","steps":["trace[1193306868] 'agreement among raft nodes before linearized reading' (duration: 118.152799ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:45.059206Z","caller":"traceutil/trace.go:171","msg":"trace[950384158] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"144.658728ms","start":"2026-02-11T09:15:44.914533Z","end":"2026-02-11T09:15:45.059191Z","steps":["trace[950384158] 'process raft request' (duration: 144.537146ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:45.062684Z","caller":"traceutil/trace.go:171","msg":"trace[437035418] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:685; }","duration":"135.423303ms","start":"2026-02-11T09:15:44.927253Z","end":"2026-02-11T09:15:45.062676Z","steps":["trace[437035418] 'read index received' (duration: 132.214721ms)","trace[437035418] 'applied index is now lower than readState.Index' (duration: 3.208112ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:15:45.062757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.492584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:15:45.062777Z","caller":"traceutil/trace.go:171","msg":"trace[509744981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"135.538056ms","start":"2026-02-11T09:15:44.927233Z","end":"2026-02-11T09:15:45.062772Z","steps":["trace[509744981] 'agreement among raft nodes before linearized reading' (duration: 135.485464ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:45.062908Z","caller":"traceutil/trace.go:171","msg":"trace[2062459941] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"140.450656ms","start":"2026-02-11T09:15:44.922453Z","end":"2026-02-11T09:15:45.062903Z","steps":["trace[2062459941] 'process raft request' (duration: 140.126917ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:45.062969Z","caller":"traceutil/trace.go:171","msg":"trace[1082527749] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"140.210049ms","start":"2026-02-11T09:15:44.922755Z","end":"2026-02-11T09:15:45.062965Z","steps":["trace[1082527749] 'process raft request' (duration: 139.883021ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:45.063211Z","caller":"traceutil/trace.go:171","msg":"trace[1096350839] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"135.916544ms","start":"2026-02-11T09:15:44.927289Z","end":"2026-02-11T09:15:45.063205Z","steps":["trace[1096350839] 'process raft request' (duration: 135.371031ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:46.260258Z","caller":"traceutil/trace.go:171","msg":"trace[1522456981] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"135.253209ms","start":"2026-02-11T09:15:46.124984Z","end":"2026-02-11T09:15:46.260237Z","steps":["trace[1522456981] 'process raft request' (duration: 134.518263ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:46.26351Z","caller":"traceutil/trace.go:171","msg":"trace[1495236285] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"128.884067ms","start":"2026-02-11T09:15:46.13461Z","end":"2026-02-11T09:15:46.263494Z","steps":["trace[1495236285] 'process raft request' (duration: 128.793925ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:46.461171Z","caller":"traceutil/trace.go:171","msg":"trace[504383111] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"164.603062ms","start":"2026-02-11T09:15:46.296511Z","end":"2026-02-11T09:15:46.461114Z","steps":["trace[504383111] 'process raft request' (duration: 65.542357ms)","trace[504383111] 'compare' (duration: 98.600755ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:15:47.066268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.100222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T09:15:47.066577Z","caller":"traceutil/trace.go:171","msg":"trace[1746145992] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"754.933047ms","start":"2026-02-11T09:15:46.311605Z","end":"2026-02-11T09:15:47.066538Z","steps":["trace[1746145992] 'process raft request' (duration: 283.494467ms)","trace[1746145992] 'compare' (duration: 470.552159ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:15:47.06665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:46.311598Z","time spent":"755.016708ms","remote":"127.0.0.1:48586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T09:15:47.066885Z","caller":"traceutil/trace.go:171","msg":"trace[1340726492] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"755.229183ms","start":"2026-02-11T09:15:46.311643Z","end":"2026-02-11T09:15:47.066872Z","steps":["trace[1340726492] 'process raft request' (duration: 754.707052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:47.066939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:46.311638Z","time spent":"755.272304ms","remote":"127.0.0.1:48800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T09:15:47.067293Z","caller":"traceutil/trace.go:171","msg":"trace[1680596687] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:721; }","duration":"145.142029ms","start":"2026-02-11T09:15:46.92214Z","end":"2026-02-11T09:15:47.067282Z","steps":["trace[1680596687] 'read index received' (duration: 56.471µs)","trace[1680596687] 'applied index is now lower than readState.Index' (duration: 145.084718ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:15:47.067432Z","caller":"traceutil/trace.go:171","msg":"trace[1574456815] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"755.691753ms","start":"2026-02-11T09:15:46.311731Z","end":"2026-02-11T09:15:47.067423Z","steps":["trace[1574456815] 'process raft request' (duration: 755.1039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:47.067478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:46.311726Z","time spent":"755.728454ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T09:15:47.067638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.493657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:15:47.067675Z","caller":"traceutil/trace.go:171","msg":"trace[448982977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"145.556258ms","start":"2026-02-11T09:15:46.922109Z","end":"2026-02-11T09:15:47.067665Z","steps":["trace[448982977] 'agreement among raft nodes before linearized reading' (duration: 145.485447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:47.067884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.819779ms","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-11T09:15:47.067912Z","caller":"traceutil/trace.go:171","msg":"trace[220774743] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:703; }","duration":"143.85616ms","start":"2026-02-11T09:15:46.924047Z","end":"2026-02-11T09:15:47.067903Z","steps":["trace[220774743] 'agreement among raft nodes before linearized reading' (duration: 143.756727ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:47.248195Z","caller":"traceutil/trace.go:171","msg":"trace[250874668] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"153.799362ms","start":"2026-02-11T09:15:47.094382Z","end":"2026-02-11T09:15:47.248181Z","steps":["trace[250874668] 'process raft request' (duration: 153.70615ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:47.254154Z","caller":"traceutil/trace.go:171","msg":"trace[1889759290] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"159.221062ms","start":"2026-02-11T09:15:47.094918Z","end":"2026-02-11T09:15:47.254139Z","steps":["trace[1889759290] 'read index received' (duration: 153.598148ms)","trace[1889759290] 'applied index is now lower than readState.Index' (duration: 5.622144ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:15:47.254285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.350535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-11T09:15:47.254303Z","caller":"traceutil/trace.go:171","msg":"trace[1169728917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:709; }","duration":"159.395406ms","start":"2026-02-11T09:15:47.094901Z","end":"2026-02-11T09:15:47.254296Z","steps":["trace[1169728917] 'agreement among raft nodes before linearized reading' (duration: 159.296314ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:47.254485Z","caller":"traceutil/trace.go:171","msg":"trace[1127558670] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"159.641562ms","start":"2026-02-11T09:15:47.094839Z","end":"2026-02-11T09:15:47.25448Z","steps":["trace[1127558670] 'process raft request' (duration: 159.125431ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:47.254595Z","caller":"traceutil/trace.go:171","msg":"trace[1914756872] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"159.56865ms","start":"2026-02-11T09:15:47.095023Z","end":"2026-02-11T09:15:47.254591Z","steps":["trace[1914756872] 'process raft request' (duration: 159.034359ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:47.254653Z","caller":"traceutil/trace.go:171","msg":"trace[1967911911] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"157.642337ms","start":"2026-02-11T09:15:47.097007Z","end":"2026-02-11T09:15:47.254649Z","steps":["trace[1967911911] 'process raft request' (duration: 157.079644ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:47.254703Z","caller":"traceutil/trace.go:171","msg":"trace[1361645821] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"157.587386ms","start":"2026-02-11T09:15:47.097111Z","end":"2026-02-11T09:15:47.254698Z","steps":["trace[1361645821] 'process raft request' (duration: 156.992753ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:47.254755Z","caller":"traceutil/trace.go:171","msg":"trace[1884564341] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"157.33223ms","start":"2026-02-11T09:15:47.097418Z","end":"2026-02-11T09:15:47.25475Z","steps":["trace[1884564341] 'process raft request' (duration: 156.701896ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:53.470598Z","caller":"traceutil/trace.go:171","msg":"trace[953204039] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"185.948388ms","start":"2026-02-11T09:15:53.284622Z","end":"2026-02-11T09:15:53.470571Z","steps":["trace[953204039] 'read index received' (duration: 185.791384ms)","trace[953204039] 'applied index is now lower than readState.Index' (duration: 156.444µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:15:53.470682Z","caller":"traceutil/trace.go:171","msg":"trace[616483394] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"189.431035ms","start":"2026-02-11T09:15:53.281246Z","end":"2026-02-11T09:15:53.470677Z","steps":["trace[616483394] 'process raft request' (duration: 189.22174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:53.470853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.449667ms","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-11T09:15:53.4709Z","caller":"traceutil/trace.go:171","msg":"trace[411972256] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:728; }","duration":"177.51807ms","start":"2026-02-11T09:15:53.293368Z","end":"2026-02-11T09:15:53.470887Z","steps":["trace[411972256] 'agreement among raft nodes before linearized reading' (duration: 177.398557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:53.470993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.675888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-11T09:15:53.471013Z","caller":"traceutil/trace.go:171","msg":"trace[1607164693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:728; }","duration":"170.729208ms","start":"2026-02-11T09:15:53.300278Z","end":"2026-02-11T09:15:53.471007Z","steps":["trace[1607164693] 'agreement among raft nodes before linearized reading' (duration: 170.632556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:53.471025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.066715ms","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-11T09:15:53.471043Z","caller":"traceutil/trace.go:171","msg":"trace[889194192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:728; }","duration":"175.094696ms","start":"2026-02-11T09:15:53.295941Z","end":"2026-02-11T09:15:53.471036Z","steps":["trace[889194192] 'agreement among raft nodes before linearized reading' (duration: 175.052335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:53.471115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.491739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T09:15:53.471129Z","caller":"traceutil/trace.go:171","msg":"trace[269666898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:728; }","duration":"186.50671ms","start":"2026-02-11T09:15:53.284619Z","end":"2026-02-11T09:15:53.471125Z","steps":["trace[269666898] 'agreement among raft nodes before linearized reading' (duration: 186.463939ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:53.822255Z","caller":"traceutil/trace.go:171","msg":"trace[410713699] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"347.993292ms","start":"2026-02-11T09:15:53.47424Z","end":"2026-02-11T09:15:53.822234Z","steps":["trace[410713699] 'process raft request' (duration: 273.062456ms)","trace[410713699] 'compare' (duration: 74.701901ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:15:53.82238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:53.474228Z","time spent":"348.074063ms","remote":"127.0.0.1:48582","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-11T09:15:53.86086Z","caller":"traceutil/trace.go:171","msg":"trace[473787520] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"385.958577ms","start":"2026-02-11T09:15:53.474883Z","end":"2026-02-11T09:15:53.860842Z","steps":["trace[473787520] 'process raft request' (duration: 385.826344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:53.86099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:53.474876Z","time spent":"386.032768ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T09:15:53.861221Z","caller":"traceutil/trace.go:171","msg":"trace[1250507433] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"385.914716ms","start":"2026-02-11T09:15:53.475263Z","end":"2026-02-11T09:15:53.861178Z","steps":["trace[1250507433] 'process raft request' (duration: 385.542828ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:15:53.861402Z","caller":"traceutil/trace.go:171","msg":"trace[897158800] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"384.104805ms","start":"2026-02-11T09:15:53.477284Z","end":"2026-02-11T09:15:53.861388Z","steps":["trace[897158800] 'process raft request' (duration: 383.810968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:15:53.861469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:53.475258Z","time spent":"386.037589ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T09:15:53.861481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:15:53.477278Z","time spent":"384.166297ms","remote":"127.0.0.1:48582","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-11T09:16:11.67475Z","caller":"traceutil/trace.go:171","msg":"trace[357222071] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"146.63251ms","start":"2026-02-11T09:16:11.528097Z","end":"2026-02-11T09:16:11.674729Z","steps":["trace[357222071] 'process raft request' (duration: 146.512497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:12.459752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.286881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T09:16:12.459967Z","caller":"traceutil/trace.go:171","msg":"trace[1275123644] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"481.824657ms","start":"2026-02-11T09:16:11.978126Z","end":"2026-02-11T09:16:12.459951Z","steps":["trace[1275123644] 'process raft request' (duration: 481.778216ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:16:12.45997Z","caller":"traceutil/trace.go:171","msg":"trace[928180928] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"482.661055ms","start":"2026-02-11T09:16:11.977285Z","end":"2026-02-11T09:16:12.459946Z","steps":["trace[928180928] 'process raft request' (duration: 213.037427ms)","trace[928180928] 'compare' (duration: 268.436782ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:16:12.460032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:11.978118Z","time spent":"481.881738ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T09:16:12.460105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:11.977273Z","time spent":"482.760768ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T09:16:12.460198Z","caller":"traceutil/trace.go:171","msg":"trace[841054877] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"482.78906ms","start":"2026-02-11T09:16:11.977401Z","end":"2026-02-11T09:16:12.46019Z","steps":["trace[841054877] 'process raft request' (duration: 482.444541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:12.460234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:11.977392Z","time spent":"482.81897ms","remote":"127.0.0.1:48582","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-11T09:16:24.718954Z","caller":"traceutil/trace.go:171","msg":"trace[79428028] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"129.867089ms","start":"2026-02-11T09:16:24.589057Z","end":"2026-02-11T09:16:24.718915Z","steps":["trace[79428028] 'process raft request' (duration: 84.66796ms)","trace[79428028] 'compare' (duration: 45.124647ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:16:29.965348Z","caller":"traceutil/trace.go:171","msg":"trace[1000665280] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"112.508116ms","start":"2026-02-11T09:16:29.852819Z","end":"2026-02-11T09:16:29.965327Z","steps":["trace[1000665280] 'read index received' (duration: 112.348043ms)","trace[1000665280] 'applied index is now lower than readState.Index' (duration: 159.173µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:16:29.965627Z","caller":"traceutil/trace.go:171","msg":"trace[632458969] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"424.811138ms","start":"2026-02-11T09:16:29.540804Z","end":"2026-02-11T09:16:29.965615Z","steps":["trace[632458969] 'process raft request' (duration: 424.413659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:29.965739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:29.540787Z","time spent":"424.869418ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T09:16:29.965984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.159451ms","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-11T09:16:29.966016Z","caller":"traceutil/trace.go:171","msg":"trace[2055302794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"113.227223ms","start":"2026-02-11T09:16:29.852778Z","end":"2026-02-11T09:16:29.966006Z","steps":["trace[2055302794] 'agreement among raft nodes before linearized reading' (duration: 113.12048ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:16:30.268115Z","caller":"traceutil/trace.go:171","msg":"trace[663585399] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"175.735273ms","start":"2026-02-11T09:16:30.092362Z","end":"2026-02-11T09:16:30.268098Z","steps":["trace[663585399] 'read index received' (duration: 174.297711ms)","trace[663585399] 'applied index is now lower than readState.Index' (duration: 1.436882ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:16:30.268293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.932787ms","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-11T09:16:30.268316Z","caller":"traceutil/trace.go:171","msg":"trace[1403458151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:887; }","duration":"176.006818ms","start":"2026-02-11T09:16:30.092301Z","end":"2026-02-11T09:16:30.268308Z","steps":["trace[1403458151] 'agreement among raft nodes before linearized reading' (duration: 175.876636ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:16:30.268486Z","caller":"traceutil/trace.go:171","msg":"trace[1072179745] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"292.81296ms","start":"2026-02-11T09:16:29.975657Z","end":"2026-02-11T09:16:30.26847Z","steps":["trace[1072179745] 'process raft request' (duration: 291.045351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:35.934191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:35.493358Z","time spent":"440.830901ms","remote":"127.0.0.1:48374","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T09:16:37.03683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.325879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-11T09:16:37.036871Z","caller":"traceutil/trace.go:171","msg":"trace[519475538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:907; }","duration":"191.40138ms","start":"2026-02-11T09:16:36.845461Z","end":"2026-02-11T09:16:37.036862Z","steps":["trace[519475538] 'range keys from in-memory index tree' (duration: 191.230847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:37.037056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.832267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:16:37.037074Z","caller":"traceutil/trace.go:171","msg":"trace[1011553004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"114.871838ms","start":"2026-02-11T09:16:36.922197Z","end":"2026-02-11T09:16:37.037069Z","steps":["trace[1011553004] 'range keys from in-memory index tree' (duration: 114.792217ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:16:38.486509Z","caller":"traceutil/trace.go:171","msg":"trace[1213649161] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"167.848869ms","start":"2026-02-11T09:16:38.318644Z","end":"2026-02-11T09:16:38.486493Z","steps":["trace[1213649161] 'read index received' (duration: 167.719766ms)","trace[1213649161] 'applied index is now lower than readState.Index' (duration: 128.603µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:16:38.48666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.996892ms","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-11T09:16:38.486688Z","caller":"traceutil/trace.go:171","msg":"trace[1700750235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:909; }","duration":"168.049074ms","start":"2026-02-11T09:16:38.318634Z","end":"2026-02-11T09:16:38.486683Z","steps":["trace[1700750235] 'agreement among raft nodes before linearized reading' (duration: 167.916291ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:16:38.486772Z","caller":"traceutil/trace.go:171","msg":"trace[79070500] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"180.572561ms","start":"2026-02-11T09:16:38.30618Z","end":"2026-02-11T09:16:38.486753Z","steps":["trace[79070500] 'process raft request' (duration: 180.219913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:39.310867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.949492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T09:16:39.310946Z","caller":"traceutil/trace.go:171","msg":"trace[840612820] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"675.521557ms","start":"2026-02-11T09:16:38.635407Z","end":"2026-02-11T09:16:39.310928Z","steps":["trace[840612820] 'read index received' (duration: 368.438462ms)","trace[840612820] 'applied index is now lower than readState.Index' (duration: 307.082025ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:16:39.311139Z","caller":"traceutil/trace.go:171","msg":"trace[1994485839] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"820.47519ms","start":"2026-02-11T09:16:38.490651Z","end":"2026-02-11T09:16:39.311126Z","steps":["trace[1994485839] 'process raft request' (duration: 513.19543ms)","trace[1994485839] 'compare' (duration: 306.714597ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:16:39.311196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:38.490635Z","time spent":"820.531071ms","remote":"127.0.0.1:48582","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-11T09:16:39.311407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.997439ms","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-11T09:16:39.311434Z","caller":"traceutil/trace.go:171","msg":"trace[262546028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:910; }","duration":"676.05982ms","start":"2026-02-11T09:16:38.635365Z","end":"2026-02-11T09:16:39.311425Z","steps":["trace[262546028] 'agreement among raft nodes before linearized reading' (duration: 675.961038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:39.31146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:38.635344Z","time spent":"676.110381ms","remote":"127.0.0.1:48582","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-11T09:16:39.311647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.419091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T09:16:39.311672Z","caller":"traceutil/trace.go:171","msg":"trace[2115546874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"668.464102ms","start":"2026-02-11T09:16:38.6432Z","end":"2026-02-11T09:16:39.311664Z","steps":["trace[2115546874] 'agreement among raft nodes before linearized reading' (duration: 668.39364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:39.311699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:38.64319Z","time spent":"668.504012ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T09:16:39.311894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.380135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-11T09:16:39.31192Z","caller":"traceutil/trace.go:171","msg":"trace[248602300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:910; }","duration":"189.439236ms","start":"2026-02-11T09:16:39.122473Z","end":"2026-02-11T09:16:39.311912Z","steps":["trace[248602300] 'agreement among raft nodes before linearized reading' (duration: 189.365584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:39.312027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.793631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:16:39.312049Z","caller":"traceutil/trace.go:171","msg":"trace[67513876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"388.843203ms","start":"2026-02-11T09:16:38.923199Z","end":"2026-02-11T09:16:39.312043Z","steps":["trace[67513876] 'agreement among raft nodes before linearized reading' (duration: 388.808922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:39.312072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:38.923185Z","time spent":"388.882103ms","remote":"127.0.0.1:48368","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-11T09:16:39.312197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.328082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T09:16:39.312223Z","caller":"traceutil/trace.go:171","msg":"trace[2121967133] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:910; }","duration":"465.382494ms","start":"2026-02-11T09:16:38.846832Z","end":"2026-02-11T09:16:39.312214Z","steps":["trace[2121967133] 'agreement among raft nodes before linearized reading' (duration: 465.341773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:39.312248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:38.846817Z","time spent":"465.423864ms","remote":"127.0.0.1:48776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-11T09:16:39.312392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.166408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:16:39.31242Z","caller":"traceutil/trace.go:171","msg":"trace[966204698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"608.21792ms","start":"2026-02-11T09:16:38.704195Z","end":"2026-02-11T09:16:39.312413Z","steps":["trace[966204698] 'agreement among raft nodes before linearized reading' (duration: 608.180709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:39.312442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:38.704181Z","time spent":"608.2558ms","remote":"127.0.0.1:48356","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-11T09:16:39.937517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.229245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T09:16:39.93761Z","caller":"traceutil/trace.go:171","msg":"trace[2026267843] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"618.311093ms","start":"2026-02-11T09:16:39.319281Z","end":"2026-02-11T09:16:39.937592Z","steps":["trace[2026267843] 'process raft request' (duration: 374.934646ms)","trace[2026267843] 'compare' (duration: 243.05225ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:16:39.937716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:39.319267Z","time spent":"618.374505ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T09:16:40.42978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.262647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T09:16:40.430025Z","caller":"traceutil/trace.go:171","msg":"trace[1031739142] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:944; }","duration":"504.186342ms","start":"2026-02-11T09:16:39.925818Z","end":"2026-02-11T09:16:40.430004Z","steps":["trace[1031739142] 'read index received' (duration: 169.570007ms)","trace[1031739142] 'applied index is now lower than readState.Index' (duration: 334.614715ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:16:40.430387Z","caller":"traceutil/trace.go:171","msg":"trace[1500422875] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"1.109528668s","start":"2026-02-11T09:16:39.320838Z","end":"2026-02-11T09:16:40.430367Z","steps":["trace[1500422875] 'process raft request' (duration: 774.588926ms)","trace[1500422875] 'compare' (duration: 333.785347ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:16:40.430473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:39.32083Z","time spent":"1.109598659s","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T09:16:40.430651Z","caller":"traceutil/trace.go:171","msg":"trace[538206093] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"1.108760492s","start":"2026-02-11T09:16:39.32188Z","end":"2026-02-11T09:16:40.43064Z","steps":["trace[538206093] 'process raft request' (duration: 1.108001895s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:40.430703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:39.321874Z","time spent":"1.108799363s","remote":"127.0.0.1:48582","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-11T09:16:40.430843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.02711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:16:40.43087Z","caller":"traceutil/trace.go:171","msg":"trace[1280693023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"505.079271ms","start":"2026-02-11T09:16:39.925782Z","end":"2026-02-11T09:16:40.430861Z","steps":["trace[1280693023] 'agreement among raft nodes before linearized reading' (duration: 505.03441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:40.430899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:39.925766Z","time spent":"505.126802ms","remote":"127.0.0.1:48356","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-11T09:16:40.693427Z","caller":"traceutil/trace.go:171","msg":"trace[2096161568] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"263.319709ms","start":"2026-02-11T09:16:40.43009Z","end":"2026-02-11T09:16:40.69341Z","steps":["trace[2096161568] 'read index received' (duration: 256.559869ms)","trace[2096161568] 'applied index is now lower than readState.Index' (duration: 6.75942ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:16:40.693583Z","caller":"traceutil/trace.go:171","msg":"trace[1953862317] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"353.023182ms","start":"2026-02-11T09:16:40.340554Z","end":"2026-02-11T09:16:40.693577Z","steps":["trace[1953862317] 'process raft request' (duration: 346.089188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:40.693646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:40.340534Z","time spent":"353.068822ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T09:16:40.693755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.235319ms","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":"info","ts":"2026-02-11T09:16:40.693775Z","caller":"traceutil/trace.go:171","msg":"trace[748281947] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:914; }","duration":"655.279901ms","start":"2026-02-11T09:16:40.03849Z","end":"2026-02-11T09:16:40.69377Z","steps":["trace[748281947] 'agreement among raft nodes before linearized reading' (duration: 655.24836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:40.693789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:40.038478Z","time spent":"655.307591ms","remote":"127.0.0.1:48476","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-11T09:16:40.924921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.78308ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T09:16:40.925039Z","caller":"traceutil/trace.go:171","msg":"trace[1171106176] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"231.559208ms","start":"2026-02-11T09:16:40.693463Z","end":"2026-02-11T09:16:40.925023Z","steps":["trace[1171106176] 'read index received' (duration: 29.623594ms)","trace[1171106176] 'applied index is now lower than readState.Index' (duration: 201.934214ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:16:40.925104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.430931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:16:40.925127Z","caller":"traceutil/trace.go:171","msg":"trace[1227756290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"491.496562ms","start":"2026-02-11T09:16:40.433623Z","end":"2026-02-11T09:16:40.925119Z","steps":["trace[1227756290] 'agreement among raft nodes before linearized reading' (duration: 491.443241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:40.925159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:40.433603Z","time spent":"491.549024ms","remote":"127.0.0.1:48368","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-11T09:16:40.925773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.653755ms","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-11T09:16:40.925814Z","caller":"traceutil/trace.go:171","msg":"trace[1682448704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:914; }","duration":"434.730257ms","start":"2026-02-11T09:16:40.491075Z","end":"2026-02-11T09:16:40.925805Z","steps":["trace[1682448704] 'agreement among raft nodes before linearized reading' (duration: 434.608785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:40.925855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:40.491057Z","time spent":"434.780239ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T09:16:43.504756Z","caller":"traceutil/trace.go:171","msg":"trace[1834935701] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"181.151893ms","start":"2026-02-11T09:16:43.32355Z","end":"2026-02-11T09:16:43.504701Z","steps":["trace[1834935701] 'read index received' (duration: 180.751464ms)","trace[1834935701] 'applied index is now lower than readState.Index' (duration: 399.369µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:16:43.504931Z","caller":"traceutil/trace.go:171","msg":"trace[1352576584] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"502.864211ms","start":"2026-02-11T09:16:43.002046Z","end":"2026-02-11T09:16:43.504911Z","steps":["trace[1352576584] 'process raft request' (duration: 502.384451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:43.505053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:43.002038Z","time spent":"502.963184ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T09:16:43.505318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.766297ms","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-11T09:16:43.505348Z","caller":"traceutil/trace.go:171","msg":"trace[660943306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"181.823228ms","start":"2026-02-11T09:16:43.323518Z","end":"2026-02-11T09:16:43.505341Z","steps":["trace[660943306] 'agreement among raft nodes before linearized reading' (duration: 181.737826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:43.50784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.821612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T09:16:43.507964Z","caller":"traceutil/trace.go:171","msg":"trace[878269440] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:924; }","duration":"164.972765ms","start":"2026-02-11T09:16:43.342981Z","end":"2026-02-11T09:16:43.507954Z","steps":["trace[878269440] 'agreement among raft nodes before linearized reading' (duration: 164.75863ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:16:43.710385Z","caller":"traceutil/trace.go:171","msg":"trace[352704081] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"201.316599ms","start":"2026-02-11T09:16:43.509048Z","end":"2026-02-11T09:16:43.710364Z","steps":["trace[352704081] 'process raft request' (duration: 197.405082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:44.096689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.445352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T09:16:44.096744Z","caller":"traceutil/trace.go:171","msg":"trace[1646386667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"148.586884ms","start":"2026-02-11T09:16:43.948145Z","end":"2026-02-11T09:16:44.096732Z","steps":["trace[1646386667] 'range keys from in-memory index tree' (duration: 148.318838ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:16:44.68222Z","caller":"traceutil/trace.go:171","msg":"trace[854912339] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"177.956273ms","start":"2026-02-11T09:16:44.504242Z","end":"2026-02-11T09:16:44.682198Z","steps":["trace[854912339] 'process raft request' (duration: 177.84827ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:16:44.708037Z","caller":"traceutil/trace.go:171","msg":"trace[78220994] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"203.657441ms","start":"2026-02-11T09:16:44.504365Z","end":"2026-02-11T09:16:44.708023Z","steps":["trace[78220994] 'process raft request' (duration: 202.835703ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:16:48.625143Z","caller":"traceutil/trace.go:171","msg":"trace[1889928199] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"442.903817ms","start":"2026-02-11T09:16:48.18222Z","end":"2026-02-11T09:16:48.625123Z","steps":["trace[1889928199] 'process raft request' (duration: 442.807445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:16:48.625267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:16:48.182194Z","time spent":"443.01434ms","remote":"127.0.0.1:48582","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-11T09:17:01.155215Z","caller":"traceutil/trace.go:171","msg":"trace[978879460] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"469.029884ms","start":"2026-02-11T09:17:00.686155Z","end":"2026-02-11T09:17:01.155185Z","steps":["trace[978879460] 'read index received' (duration: 385.347345ms)","trace[978879460] 'applied index is now lower than readState.Index' (duration: 83.680859ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:17:01.155679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.503705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-11T09:17:01.155732Z","caller":"traceutil/trace.go:171","msg":"trace[1103398920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1031; }","duration":"469.609076ms","start":"2026-02-11T09:17:00.68611Z","end":"2026-02-11T09:17:01.155719Z","steps":["trace[1103398920] 'agreement among raft nodes before linearized reading' (duration: 469.239038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:17:01.156064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:17:00.686093Z","time spent":"469.846652ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-11T09:17:01.196286Z","caller":"traceutil/trace.go:171","msg":"trace[1065355873] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"123.616382ms","start":"2026-02-11T09:17:01.072647Z","end":"2026-02-11T09:17:01.196263Z","steps":["trace[1065355873] 'process raft request' (duration: 123.452758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:17:01.196363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.625083ms","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-11T09:17:01.196631Z","caller":"traceutil/trace.go:171","msg":"trace[155946687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1032; }","duration":"338.853538ms","start":"2026-02-11T09:17:00.85769Z","end":"2026-02-11T09:17:01.196543Z","steps":["trace[155946687] 'agreement among raft nodes before linearized reading' (duration: 338.554892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:17:01.196741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:17:00.857676Z","time spent":"339.025432ms","remote":"127.0.0.1:48582","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-11T09:17:01.19712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.842665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:17:01.197233Z","caller":"traceutil/trace.go:171","msg":"trace[963455096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"271.007709ms","start":"2026-02-11T09:17:00.926213Z","end":"2026-02-11T09:17:01.197221Z","steps":["trace[963455096] 'agreement among raft nodes before linearized reading' (duration: 270.843895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:17:01.197686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.842129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-11T09:17:01.1978Z","caller":"traceutil/trace.go:171","msg":"trace[1148413233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1032; }","duration":"334.974721ms","start":"2026-02-11T09:17:00.862813Z","end":"2026-02-11T09:17:01.197788Z","steps":["trace[1148413233] 'agreement among raft nodes before linearized reading' (duration: 334.766247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:17:01.197893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:17:00.862804Z","time spent":"335.076863ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T09:17:01.284679Z","caller":"traceutil/trace.go:171","msg":"trace[1427951012] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"124.785997ms","start":"2026-02-11T09:17:01.159874Z","end":"2026-02-11T09:17:01.28466Z","steps":["trace[1427951012] 'process raft request' (duration: 121.31853ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:17:50.033166Z","caller":"traceutil/trace.go:171","msg":"trace[1521028592] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"103.270462ms","start":"2026-02-11T09:17:49.929883Z","end":"2026-02-11T09:17:50.033153Z","steps":["trace[1521028592] 'read index received' (duration: 103.16869ms)","trace[1521028592] 'applied index is now lower than readState.Index' (duration: 101.182µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:17:50.033314Z","caller":"traceutil/trace.go:171","msg":"trace[274532552] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"106.399381ms","start":"2026-02-11T09:17:49.926909Z","end":"2026-02-11T09:17:50.033308Z","steps":["trace[274532552] 'process raft request' (duration: 106.175966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:17:50.03342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.524508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:17:50.033436Z","caller":"traceutil/trace.go:171","msg":"trace[1040792701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1235; }","duration":"103.559518ms","start":"2026-02-11T09:17:49.929872Z","end":"2026-02-11T09:17:50.033431Z","steps":["trace[1040792701] 'agreement among raft nodes before linearized reading' (duration: 103.523567ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:18:48.059653Z","caller":"traceutil/trace.go:171","msg":"trace[462642612] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"104.529952ms","start":"2026-02-11T09:18:47.955108Z","end":"2026-02-11T09:18:48.059638Z","steps":["trace[462642612] 'process raft request' (duration: 104.301947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:18:50.236366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.608125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T09:18:50.236603Z","caller":"traceutil/trace.go:171","msg":"trace[1845729369] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"146.684366ms","start":"2026-02-11T09:18:50.089901Z","end":"2026-02-11T09:18:50.236585Z","steps":["trace[1845729369] 'process raft request' (duration: 36.776104ms)","trace[1845729369] 'compare' (duration: 109.498512ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:18:50.236812Z","caller":"traceutil/trace.go:171","msg":"trace[1750659418] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"145.783924ms","start":"2026-02-11T09:18:50.091022Z","end":"2026-02-11T09:18:50.236806Z","steps":["trace[1750659418] 'process raft request' (duration: 145.515679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:18:58.229237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.539263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-11T09:18:58.229287Z","caller":"traceutil/trace.go:171","msg":"trace[1933473167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1510; }","duration":"109.597244ms","start":"2026-02-11T09:18:58.119678Z","end":"2026-02-11T09:18:58.229275Z","steps":["trace[1933473167] 'range keys from in-memory index tree' (duration: 109.41342ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:19:58.885234Z","caller":"traceutil/trace.go:171","msg":"trace[1979633468] linearizableReadLoop","detail":"{readStateIndex:1849; appliedIndex:1848; }","duration":"242.691653ms","start":"2026-02-11T09:19:58.642518Z","end":"2026-02-11T09:19:58.88521Z","steps":["trace[1979633468] 'read index received' (duration: 226.142905ms)","trace[1979633468] 'applied index is now lower than readState.Index' (duration: 16.547818ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:19:58.885643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.080911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T09:19:58.885686Z","caller":"traceutil/trace.go:171","msg":"trace[1946653330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1775; }","duration":"225.137392ms","start":"2026-02-11T09:19:58.660538Z","end":"2026-02-11T09:19:58.885675Z","steps":["trace[1946653330] 'agreement among raft nodes before linearized reading' (duration: 224.989409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:19:58.886335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.199844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-11T09:19:58.886377Z","caller":"traceutil/trace.go:171","msg":"trace[1134009624] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1775; }","duration":"221.264526ms","start":"2026-02-11T09:19:58.665102Z","end":"2026-02-11T09:19:58.886367Z","steps":["trace[1134009624] 'agreement among raft nodes before linearized reading' (duration: 221.132693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:19:58.886747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.314526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5e2cffa4-8a3f-4aee-849c-71cb83ec29ab\" ","response":"range_response_count:1 size:2174"} {"level":"info","ts":"2026-02-11T09:19:58.888064Z","caller":"traceutil/trace.go:171","msg":"trace[1547425426] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5e2cffa4-8a3f-4aee-849c-71cb83ec29ab; range_end:; response_count:1; response_revision:1775; }","duration":"245.558456ms","start":"2026-02-11T09:19:58.642488Z","end":"2026-02-11T09:19:58.888047Z","steps":["trace[1547425426] 'agreement among raft nodes before linearized reading' (duration: 243.002789ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:19:58.888445Z","caller":"traceutil/trace.go:171","msg":"trace[750134843] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"246.046437ms","start":"2026-02-11T09:19:58.642383Z","end":"2026-02-11T09:19:58.888429Z","steps":["trace[750134843] 'process raft request' (duration: 226.254668ms)","trace[750134843] 'compare' (duration: 16.450155ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:20:07.171307Z","caller":"traceutil/trace.go:171","msg":"trace[664278404] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"129.676221ms","start":"2026-02-11T09:20:07.041599Z","end":"2026-02-11T09:20:07.171275Z","steps":["trace[664278404] 'process raft request' (duration: 80.974958ms)","trace[664278404] 'compare' (duration: 48.283204ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:20:11.046674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.078264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-11T09:20:11.046856Z","caller":"traceutil/trace.go:171","msg":"trace[735493640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1831; }","duration":"294.35696ms","start":"2026-02-11T09:20:10.752478Z","end":"2026-02-11T09:20:11.046835Z","steps":["trace[735493640] 'range keys from in-memory index tree' (duration: 293.803948ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:20:11.069715Z","caller":"traceutil/trace.go:171","msg":"trace[859521689] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1907; }","duration":"143.740714ms","start":"2026-02-11T09:20:10.925956Z","end":"2026-02-11T09:20:11.069696Z","steps":["trace[859521689] 'read index received' (duration: 70.580678ms)","trace[859521689] 'applied index is now lower than readState.Index' (duration: 73.158936ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:20:11.071209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.241758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:20:11.071881Z","caller":"traceutil/trace.go:171","msg":"trace[830250446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1832; }","duration":"145.919052ms","start":"2026-02-11T09:20:10.925892Z","end":"2026-02-11T09:20:11.071812Z","steps":["trace[830250446] 'agreement among raft nodes before linearized reading' (duration: 144.103343ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:20:11.07252Z","caller":"traceutil/trace.go:171","msg":"trace[1896047884] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"158.372219ms","start":"2026-02-11T09:20:10.914107Z","end":"2026-02-11T09:20:11.07248Z","steps":["trace[1896047884] 'process raft request' (duration: 155.480825ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:20:11.225155Z","caller":"traceutil/trace.go:171","msg":"trace[483215261] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"126.542312ms","start":"2026-02-11T09:20:11.098578Z","end":"2026-02-11T09:20:11.22512Z","steps":["trace[483215261] 'process raft request' (duration: 117.853209ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:20:11.240501Z","caller":"traceutil/trace.go:171","msg":"trace[1699176892] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"141.821121ms","start":"2026-02-11T09:20:11.098649Z","end":"2026-02-11T09:20:11.24047Z","steps":["trace[1699176892] 'process raft request' (duration: 141.294849ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:20:13.541494Z","caller":"traceutil/trace.go:171","msg":"trace[261386457] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"253.778259ms","start":"2026-02-11T09:20:13.287694Z","end":"2026-02-11T09:20:13.541473Z","steps":["trace[261386457] 'process raft request' (duration: 253.662917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:17.949399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.639872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-11T09:20:17.950321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.738146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T09:20:17.950349Z","caller":"traceutil/trace.go:171","msg":"trace[1557309452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1862; }","duration":"373.810938ms","start":"2026-02-11T09:20:17.576526Z","end":"2026-02-11T09:20:17.950337Z","steps":["trace[1557309452] 'range keys from in-memory index tree' (duration: 373.581932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:17.950378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:17.576503Z","time spent":"373.870259ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T09:20:17.950587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.485528ms","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-11T09:20:17.950601Z","caller":"traceutil/trace.go:171","msg":"trace[80359016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1862; }","duration":"777.5451ms","start":"2026-02-11T09:20:17.173051Z","end":"2026-02-11T09:20:17.950596Z","steps":["trace[80359016] 'range keys from in-memory index tree' (duration: 777.381886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:17.950613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:17.172944Z","time spent":"777.666612ms","remote":"127.0.0.1:48582","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-11T09:20:17.950797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.421951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-11T09:20:17.95081Z","caller":"traceutil/trace.go:171","msg":"trace[860501292] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1862; }","duration":"680.466861ms","start":"2026-02-11T09:20:17.270339Z","end":"2026-02-11T09:20:17.950806Z","steps":["trace[860501292] 'range keys from in-memory index tree' (duration: 680.345779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:17.950822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:17.270321Z","time spent":"680.498262ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-11T09:20:17.950905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.241123ms","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-11T09:20:17.950917Z","caller":"traceutil/trace.go:171","msg":"trace[1709644299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1862; }","duration":"674.296594ms","start":"2026-02-11T09:20:17.276617Z","end":"2026-02-11T09:20:17.950914Z","steps":["trace[1709644299] 'range keys from in-memory index tree' (duration: 674.155552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:17.950955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:17.276594Z","time spent":"674.357636ms","remote":"127.0.0.1:48582","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-11T09:20:17.951046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.406695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-11T09:20:17.951062Z","caller":"traceutil/trace.go:171","msg":"trace[2099780300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1862; }","duration":"642.471787ms","start":"2026-02-11T09:20:17.308586Z","end":"2026-02-11T09:20:17.951057Z","steps":["trace[2099780300] 'range keys from in-memory index tree' (duration: 642.278493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:17.951073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:17.308558Z","time spent":"642.511908ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T09:20:17.952052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.401955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-11T09:20:18.162145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.593121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-11T09:20:18.162222Z","caller":"traceutil/trace.go:171","msg":"trace[1661848147] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1862; }","duration":"513.702163ms","start":"2026-02-11T09:20:17.648501Z","end":"2026-02-11T09:20:18.162203Z","steps":["trace[1661848147] 'range keys from in-memory index tree' (duration: 513.392187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:18.162259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:17.648491Z","time spent":"513.759874ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-11T09:20:18.162385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.688635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-11T09:20:18.162417Z","caller":"traceutil/trace.go:171","msg":"trace[1473871681] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1862; }","duration":"469.764317ms","start":"2026-02-11T09:20:17.692644Z","end":"2026-02-11T09:20:18.162408Z","steps":["trace[1473871681] 'range keys from in-memory index tree' (duration: 469.580302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:18.162447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:17.692619Z","time spent":"469.821978ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-11T09:20:18.162563Z","caller":"traceutil/trace.go:171","msg":"trace[1989180593] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1862; }","duration":"868.782035ms","start":"2026-02-11T09:20:17.080666Z","end":"2026-02-11T09:20:17.949448Z","steps":["trace[1989180593] 'count revisions from in-memory index tree' (duration: 868.52575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:18.162604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:17.080642Z","time spent":"1.081948851s","remote":"127.0.0.1:48510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-02-11T09:20:18.162913Z","caller":"traceutil/trace.go:171","msg":"trace[862773071] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1862; }","duration":"313.457376ms","start":"2026-02-11T09:20:17.638606Z","end":"2026-02-11T09:20:17.952063Z","steps":["trace[862773071] 'count revisions from in-memory index tree' (duration: 313.344723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:18.162958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:17.63859Z","time spent":"524.355572ms","remote":"127.0.0.1:52868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-02-11T09:20:18.834528Z","caller":"traceutil/trace.go:171","msg":"trace[1168243109] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"672.592455ms","start":"2026-02-11T09:20:18.161906Z","end":"2026-02-11T09:20:18.834498Z","steps":["trace[1168243109] 'process raft request' (duration: 672.408451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:18.834888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:18.161883Z","time spent":"672.759109ms","remote":"127.0.0.1:48582","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-11T09:20:18.664815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114732625966886,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T09:20:19.230301Z","caller":"traceutil/trace.go:171","msg":"trace[2047683814] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1941; }","duration":"1.066608679s","start":"2026-02-11T09:20:18.163668Z","end":"2026-02-11T09:20:19.230277Z","steps":["trace[2047683814] 'read index received' (duration: 792.870727ms)","trace[2047683814] 'applied index is now lower than readState.Index' (duration: 273.736852ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:20:19.230655Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":10485114732625966894,"received-request-id":10485114732625966886} {"level":"info","ts":"2026-02-11T09:20:19.23068Z","caller":"traceutil/trace.go:171","msg":"trace[1025967436] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"1.066930406s","start":"2026-02-11T09:20:18.163717Z","end":"2026-02-11T09:20:19.230648Z","steps":["trace[1025967436] 'process raft request' (duration: 1.065888503s)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:20:19.230974Z","caller":"traceutil/trace.go:171","msg":"trace[1300667941] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"1.067352906s","start":"2026-02-11T09:20:18.163607Z","end":"2026-02-11T09:20:19.23096Z","steps":["trace[1300667941] 'process raft request' (duration: 1.04817111s)","trace[1300667941] 'compare' (duration: 17.405726ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:20:19.231111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:18.163594Z","time spent":"1.067428208s","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T09:20:19.23133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:18.163709Z","time spent":"1.067284805s","remote":"127.0.0.1:48582","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-11T09:20:19.231684Z","caller":"traceutil/trace.go:171","msg":"trace[1833456593] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"1.061811293s","start":"2026-02-11T09:20:18.169862Z","end":"2026-02-11T09:20:19.231673Z","steps":["trace[1833456593] 'process raft request' (duration: 1.060334301s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:19.231754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:18.169852Z","time spent":"1.061869234s","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T09:20:19.231861Z","caller":"traceutil/trace.go:171","msg":"trace[1146779360] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"1.068017281s","start":"2026-02-11T09:20:18.163831Z","end":"2026-02-11T09:20:19.231848Z","steps":["trace[1146779360] 'process raft request' (duration: 1.065829462s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:19.231912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:18.163823Z","time spent":"1.068063982s","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T09:20:19.231985Z","caller":"traceutil/trace.go:171","msg":"trace[2073829013] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"1.068097263s","start":"2026-02-11T09:20:18.16388Z","end":"2026-02-11T09:20:19.231978Z","steps":["trace[2073829013] 'process raft request' (duration: 1.065811262s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:19.232036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:18.163875Z","time spent":"1.068135003s","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T09:20:19.232145Z","caller":"traceutil/trace.go:171","msg":"trace[1912345305] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"1.062185731s","start":"2026-02-11T09:20:18.169951Z","end":"2026-02-11T09:20:19.232136Z","steps":["trace[1912345305] 'process raft request' (duration: 1.0602892s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:19.232195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:18.169946Z","time spent":"1.062224152s","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T09:20:19.23228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068609554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T09:20:19.232314Z","caller":"traceutil/trace.go:171","msg":"trace[1915655719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1869; }","duration":"1.068641525s","start":"2026-02-11T09:20:18.163664Z","end":"2026-02-11T09:20:19.232306Z","steps":["trace[1915655719] 'agreement among raft nodes before linearized reading' (duration: 1.068589674s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:19.232344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:18.163641Z","time spent":"1.068698396s","remote":"127.0.0.1:48356","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-11T09:20:19.23257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.583458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-11T09:20:19.232607Z","caller":"traceutil/trace.go:171","msg":"trace[2131958718] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1869; }","duration":"746.66821ms","start":"2026-02-11T09:20:18.485931Z","end":"2026-02-11T09:20:19.232599Z","steps":["trace[2131958718] 'agreement among raft nodes before linearized reading' (duration: 746.550758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:19.232639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T09:20:18.485914Z","time spent":"746.718681ms","remote":"127.0.0.1:48582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-11T09:20:26.402218Z","caller":"traceutil/trace.go:171","msg":"trace[732720080] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"123.761851ms","start":"2026-02-11T09:20:26.278433Z","end":"2026-02-11T09:20:26.402195Z","steps":["trace[732720080] 'process raft request' (duration: 83.848873ms)","trace[732720080] 'compare' (duration: 39.632092ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:20:26.611979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.125623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-02-11T09:20:26.612085Z","caller":"traceutil/trace.go:171","msg":"trace[469379689] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap; range_end:; response_count:1; response_revision:1904; }","duration":"107.278676ms","start":"2026-02-11T09:20:26.504786Z","end":"2026-02-11T09:20:26.612065Z","steps":["trace[469379689] 'range keys from in-memory index tree' (duration: 107.01138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:26.612249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.035539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" ","response":"range_response_count:1 size:684"} {"level":"info","ts":"2026-02-11T09:20:26.612348Z","caller":"traceutil/trace.go:171","msg":"trace[83391289] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver; range_end:; response_count:1; response_revision:1904; }","duration":"102.178182ms","start":"2026-02-11T09:20:26.510148Z","end":"2026-02-11T09:20:26.612326Z","steps":["trace[83391289] 'range keys from in-memory index tree' (duration: 101.988298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:27.290241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.977864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:952"} {"level":"info","ts":"2026-02-11T09:20:27.290319Z","caller":"traceutil/trace.go:171","msg":"trace[1928931487] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1905; }","duration":"172.087417ms","start":"2026-02-11T09:20:27.118212Z","end":"2026-02-11T09:20:27.2903Z","steps":["trace[1928931487] 'range keys from in-memory index tree' (duration: 171.896492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:27.290573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.430337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:767"} {"level":"info","ts":"2026-02-11T09:20:27.290614Z","caller":"traceutil/trace.go:171","msg":"trace[52159717] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1905; }","duration":"133.501188ms","start":"2026-02-11T09:20:27.157104Z","end":"2026-02-11T09:20:27.290605Z","steps":["trace[52159717] 'range keys from in-memory index tree' (duration: 133.374146ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:20:27.480776Z","caller":"traceutil/trace.go:171","msg":"trace[1866355388] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1986; }","duration":"131.087165ms","start":"2026-02-11T09:20:27.349671Z","end":"2026-02-11T09:20:27.480758Z","steps":["trace[1866355388] 'read index received' (duration: 70.952438ms)","trace[1866355388] 'applied index is now lower than readState.Index' (duration: 60.133917ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:20:27.480882Z","caller":"traceutil/trace.go:171","msg":"trace[348103393] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"133.272783ms","start":"2026-02-11T09:20:27.347599Z","end":"2026-02-11T09:20:27.480872Z","steps":["trace[348103393] 'process raft request' (duration: 133.12009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:27.481205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.518474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-11T09:20:27.481248Z","caller":"traceutil/trace.go:171","msg":"trace[829701549] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1908; }","duration":"131.583065ms","start":"2026-02-11T09:20:27.349655Z","end":"2026-02-11T09:20:27.481238Z","steps":["trace[829701549] 'agreement among raft nodes before linearized reading' (duration: 131.440252ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:20:27.482015Z","caller":"traceutil/trace.go:171","msg":"trace[1921217651] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"135.164844ms","start":"2026-02-11T09:20:27.346817Z","end":"2026-02-11T09:20:27.481982Z","steps":["trace[1921217651] 'process raft request' (duration: 73.800751ms)","trace[1921217651] 'compare' (duration: 59.989983ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:20:29.619623Z","caller":"traceutil/trace.go:171","msg":"trace[831438049] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"124.800934ms","start":"2026-02-11T09:20:29.4948Z","end":"2026-02-11T09:20:29.619601Z","steps":["trace[831438049] 'process raft request' (duration: 124.656131ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:20:29.637321Z","caller":"traceutil/trace.go:171","msg":"trace[1912054085] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"137.488978ms","start":"2026-02-11T09:20:29.499816Z","end":"2026-02-11T09:20:29.637305Z","steps":["trace[1912054085] 'process raft request' (duration: 137.333884ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:20:33.91828Z","caller":"traceutil/trace.go:171","msg":"trace[1049048322] linearizableReadLoop","detail":"{readStateIndex:2014; appliedIndex:2013; }","duration":"100.081565ms","start":"2026-02-11T09:20:33.818178Z","end":"2026-02-11T09:20:33.91826Z","steps":["trace[1049048322] 'read index received' (duration: 78.72159ms)","trace[1049048322] 'applied index is now lower than readState.Index' (duration: 21.359055ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:20:33.918554Z","caller":"traceutil/trace.go:171","msg":"trace[1491109087] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"103.672214ms","start":"2026-02-11T09:20:33.814864Z","end":"2026-02-11T09:20:33.918536Z","steps":["trace[1491109087] 'process raft request' (duration: 82.084724ms)","trace[1491109087] 'compare' (duration: 21.226492ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:20:33.918899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.715169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:route-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-02-11T09:20:33.918954Z","caller":"traceutil/trace.go:171","msg":"trace[1891780491] range","detail":"{range_begin:/registry/clusterroles/system:controller:route-controller; range_end:; response_count:1; response_revision:1933; }","duration":"100.78727ms","start":"2026-02-11T09:20:33.818153Z","end":"2026-02-11T09:20:33.91894Z","steps":["trace[1891780491] 'agreement among raft nodes before linearized reading' (duration: 100.688118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T09:20:34.135144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.329254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-account-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-02-11T09:20:34.1352Z","caller":"traceutil/trace.go:171","msg":"trace[1640330332] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-account-controller; range_end:; response_count:1; response_revision:1933; }","duration":"115.414916ms","start":"2026-02-11T09:20:34.019771Z","end":"2026-02-11T09:20:34.135186Z","steps":["trace[1640330332] 'range keys from in-memory index tree' (duration: 115.258623ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T09:20:37.805172Z","caller":"traceutil/trace.go:171","msg":"trace[206874168] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"118.231568ms","start":"2026-02-11T09:20:37.686922Z","end":"2026-02-11T09:20:37.805154Z","steps":["trace[206874168] 'process raft request' (duration: 68.009012ms)","trace[206874168] 'compare' (duration: 49.91211ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:20:44.583533Z","caller":"traceutil/trace.go:171","msg":"trace[1123291308] linearizableReadLoop","detail":"{readStateIndex:2060; appliedIndex:2059; }","duration":"122.767749ms","start":"2026-02-11T09:20:44.460741Z","end":"2026-02-11T09:20:44.583509Z","steps":["trace[1123291308] 'read index received' (duration: 63.848779ms)","trace[1123291308] 'applied index is now lower than readState.Index' (duration: 58.91808ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T09:20:44.583883Z","caller":"traceutil/trace.go:171","msg":"trace[943506882] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"129.08311ms","start":"2026-02-11T09:20:44.454731Z","end":"2026-02-11T09:20:44.583814Z","steps":["trace[943506882] 'process raft request' (duration: 69.861413ms)","trace[943506882] 'compare' (duration: 58.818928ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T09:20:44.584319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.304632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-nlbhj\" ","response":"range_response_count:1 size:4844"} {"level":"info","ts":"2026-02-11T09:20:44.584781Z","caller":"traceutil/trace.go:171","msg":"trace[1263706145] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-nlbhj; range_end:; response_count:1; response_revision:1977; }","duration":"124.032568ms","start":"2026-02-11T09:20:44.460731Z","end":"2026-02-11T09:20:44.584763Z","steps":["trace[1263706145] 'agreement among raft nodes before linearized reading' (duration: 123.21701ms)"],"step_count":1}