{"level":"warn","ts":"2026-02-17T18:36:56.093254Z","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-17T18:36:56.093397Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.195: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://162.253.55.195:2380","--initial-cluster=instance=https://162.253.55.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.195: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-17T18:36:56.093772Z","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-17T18:36:56.093792Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-17T18:36:56.093929Z","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-17T18:36:56.096326Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"]} {"level":"info","ts":"2026-02-17T18:36:56.10112Z","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://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.195: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-17T18:36:56.111677Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.685894ms"} {"level":"info","ts":"2026-02-17T18:36:56.152372Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-02-17T18:36:56.152533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T18:36:56.152562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-02-17T18:36:56.152617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b66d12fd59101739 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T18:36:56.152639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-02-17T18:36:56.152679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-02-17T18:36:56.162736Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T18:36:56.165446Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T18:36:56.173485Z","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-17T18:36:56.17977Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b66d12fd59101739","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T18:36:56.179906Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b66d12fd59101739","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T18:36:56.180457Z","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-17T18:36:56.180511Z","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-17T18:36:56.180581Z","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-17T18:36:56.184361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-02-17T18:36:56.184699Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","added-peer-id":"b66d12fd59101739","added-peer-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-17T18:36:56.201856Z","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-17T18:36:56.202137Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-17T18:36:56.203969Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-17T18:36:56.204161Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b66d12fd59101739","initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T18:36:56.204209Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T18:36:56.853409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T18:36:56.853473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T18:36:56.853495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-02-17T18:36:56.853511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-02-17T18:36:56.853516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-17T18:36:56.853525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-02-17T18:36:56.853534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-17T18:36:56.944761Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T18:36:56.968594Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b66d12fd59101739","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.195:2379]}","request-path":"/0/members/b66d12fd59101739/attributes","cluster-id":"a64a525934ce8379","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T18:36:56.968692Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T18:36:56.970793Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T18:36:56.970931Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T18:36:56.972778Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T18:36:56.972816Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T18:36:57.004718Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-02-17T18:36:57.124022Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T18:36:57.124181Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T18:36:57.124238Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T18:36:59.366696Z","caller":"traceutil/trace.go:171","msg":"trace[1864128001] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"197.644174ms","start":"2026-02-17T18:36:59.168959Z","end":"2026-02-17T18:36:59.366603Z","steps":["trace[1864128001] 'process raft request' (duration: 160.458086ms)","trace[1864128001] 'compare' (duration: 31.782978ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:36:59.369112Z","caller":"traceutil/trace.go:171","msg":"trace[734008254] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"199.856724ms","start":"2026-02-17T18:36:59.169242Z","end":"2026-02-17T18:36:59.369099Z","steps":["trace[734008254] 'process raft request' (duration: 197.305027ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.370146Z","caller":"traceutil/trace.go:171","msg":"trace[268566580] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"199.511807ms","start":"2026-02-17T18:36:59.170628Z","end":"2026-02-17T18:36:59.37014Z","steps":["trace[268566580] 'process raft request' (duration: 195.950597ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.371002Z","caller":"traceutil/trace.go:171","msg":"trace[1670448994] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"200.295684ms","start":"2026-02-17T18:36:59.1707Z","end":"2026-02-17T18:36:59.370996Z","steps":["trace[1670448994] 'process raft request' (duration: 199.337962ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.372336Z","caller":"traceutil/trace.go:171","msg":"trace[799713126] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"201.596082ms","start":"2026-02-17T18:36:59.170733Z","end":"2026-02-17T18:36:59.372329Z","steps":["trace[799713126] 'process raft request' (duration: 199.331402ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.373818Z","caller":"traceutil/trace.go:171","msg":"trace[2109515564] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"203.036895ms","start":"2026-02-17T18:36:59.170772Z","end":"2026-02-17T18:36:59.373809Z","steps":["trace[2109515564] 'process raft request' (duration: 200.161271ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.374594Z","caller":"traceutil/trace.go:171","msg":"trace[1336301826] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"200.230091ms","start":"2026-02-17T18:36:59.174358Z","end":"2026-02-17T18:36:59.374588Z","steps":["trace[1336301826] 'process raft request' (duration: 196.59096ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.374806Z","caller":"traceutil/trace.go:171","msg":"trace[901007157] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"195.415675ms","start":"2026-02-17T18:36:59.17938Z","end":"2026-02-17T18:36:59.374796Z","steps":["trace[901007157] 'process raft request' (duration: 194.405212ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.375143Z","caller":"traceutil/trace.go:171","msg":"trace[1812611404] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:4; }","duration":"190.934584ms","start":"2026-02-17T18:36:59.184202Z","end":"2026-02-17T18:36:59.375137Z","steps":["trace[1812611404] 'read index received' (duration: 61.864068ms)","trace[1812611404] 'applied index is now lower than readState.Index' (duration: 129.069686ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:36:59.375322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.094268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:36:59.375353Z","caller":"traceutil/trace.go:171","msg":"trace[1628382269] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"191.1726ms","start":"2026-02-17T18:36:59.184173Z","end":"2026-02-17T18:36:59.375346Z","steps":["trace[1628382269] 'agreement among raft nodes before linearized reading' (duration: 191.056367ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.375074Z","caller":"traceutil/trace.go:171","msg":"trace[1206471697] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"120.385782ms","start":"2026-02-17T18:36:59.254674Z","end":"2026-02-17T18:36:59.37506Z","steps":["trace[1206471697] 'process raft request' (duration: 119.84385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:36:59.455686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.301736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:36:59.455851Z","caller":"traceutil/trace.go:171","msg":"trace[1395485483] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"227.448978ms","start":"2026-02-17T18:36:59.228328Z","end":"2026-02-17T18:36:59.455777Z","steps":["trace[1395485483] 'agreement among raft nodes before linearized reading' (duration: 227.172982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:36:59.458692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.189799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:36:59.458722Z","caller":"traceutil/trace.go:171","msg":"trace[174919033] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"151.275501ms","start":"2026-02-17T18:36:59.307439Z","end":"2026-02-17T18:36:59.458714Z","steps":["trace[174919033] 'agreement among raft nodes before linearized reading' (duration: 151.207579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:36:59.459037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.242522ms","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-17T18:36:59.459069Z","caller":"traceutil/trace.go:171","msg":"trace[1179440620] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:18; }","duration":"204.289402ms","start":"2026-02-17T18:36:59.254769Z","end":"2026-02-17T18:36:59.459058Z","steps":["trace[1179440620] 'agreement among raft nodes before linearized reading' (duration: 204.227181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:36:59.459337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.713861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:36:59.459363Z","caller":"traceutil/trace.go:171","msg":"trace[381223821] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"204.750603ms","start":"2026-02-17T18:36:59.254606Z","end":"2026-02-17T18:36:59.459356Z","steps":["trace[381223821] 'agreement among raft nodes before linearized reading' (duration: 204.692802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:36:59.45961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.885042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:36:59.45986Z","caller":"traceutil/trace.go:171","msg":"trace[676367290] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:0; response_revision:18; }","duration":"208.17146ms","start":"2026-02-17T18:36:59.25168Z","end":"2026-02-17T18:36:59.459852Z","steps":["trace[676367290] 'agreement among raft nodes before linearized reading' (duration: 207.909963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:36:59.460047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.84775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:36:59.460197Z","caller":"traceutil/trace.go:171","msg":"trace[650821234] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"233.345881ms","start":"2026-02-17T18:36:59.22682Z","end":"2026-02-17T18:36:59.460166Z","steps":["trace[650821234] 'agreement among raft nodes before linearized reading' (duration: 228.889261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:36:59.460573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.033144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:36:59.460725Z","caller":"traceutil/trace.go:171","msg":"trace[1754412214] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"242.230669ms","start":"2026-02-17T18:36:59.218482Z","end":"2026-02-17T18:36:59.460712Z","steps":["trace[1754412214] 'agreement among raft nodes before linearized reading' (duration: 242.028154ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.553869Z","caller":"traceutil/trace.go:171","msg":"trace[731767368] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"136.283148ms","start":"2026-02-17T18:36:59.417501Z","end":"2026-02-17T18:36:59.553784Z","steps":["trace[731767368] 'process raft request' (duration: 44.832019ms)","trace[731767368] 'compare' (duration: 90.578279ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:36:59.554784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.380152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3179"} {"level":"info","ts":"2026-02-17T18:36:59.554841Z","caller":"traceutil/trace.go:171","msg":"trace[1329703463] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"168.481874ms","start":"2026-02-17T18:36:59.386343Z","end":"2026-02-17T18:36:59.554825Z","steps":["trace[1329703463] 'agreement among raft nodes before linearized reading' (duration: 168.326661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:36:59.555264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.59751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-f86km\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-02-17T18:36:59.555306Z","caller":"traceutil/trace.go:171","msg":"trace[1260646212] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-f86km; range_end:; response_count:1; response_revision:19; }","duration":"169.665492ms","start":"2026-02-17T18:36:59.385631Z","end":"2026-02-17T18:36:59.555296Z","steps":["trace[1260646212] 'agreement among raft nodes before linearized reading' (duration: 169.58865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:36:59.808178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.395675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-17T18:36:59.808594Z","caller":"traceutil/trace.go:171","msg":"trace[1662321730] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"229.038865ms","start":"2026-02-17T18:36:59.579533Z","end":"2026-02-17T18:36:59.808572Z","steps":["trace[1662321730] 'process raft request' (duration: 229.006034ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.808627Z","caller":"traceutil/trace.go:171","msg":"trace[1472263552] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"235.15043ms","start":"2026-02-17T18:36:59.573343Z","end":"2026-02-17T18:36:59.808494Z","steps":["trace[1472263552] 'process raft request' (duration: 235.020347ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.808922Z","caller":"traceutil/trace.go:171","msg":"trace[1063118943] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"243.451495ms","start":"2026-02-17T18:36:59.565454Z","end":"2026-02-17T18:36:59.808906Z","steps":["trace[1063118943] 'process raft request' (duration: 94.69629ms)","trace[1063118943] 'compare' (duration: 147.209571ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:36:59.809032Z","caller":"traceutil/trace.go:171","msg":"trace[960737985] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"234.684001ms","start":"2026-02-17T18:36:59.574337Z","end":"2026-02-17T18:36:59.809021Z","steps":["trace[960737985] 'process raft request' (duration: 234.063557ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.809123Z","caller":"traceutil/trace.go:171","msg":"trace[97102850] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"236.537841ms","start":"2026-02-17T18:36:59.572564Z","end":"2026-02-17T18:36:59.809102Z","steps":["trace[97102850] 'process raft request' (duration: 235.751494ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.809226Z","caller":"traceutil/trace.go:171","msg":"trace[1752203759] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"232.370699ms","start":"2026-02-17T18:36:59.576844Z","end":"2026-02-17T18:36:59.809215Z","steps":["trace[1752203759] 'process raft request' (duration: 231.594252ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.809439Z","caller":"traceutil/trace.go:171","msg":"trace[438062236] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"232.545372ms","start":"2026-02-17T18:36:59.576881Z","end":"2026-02-17T18:36:59.809426Z","steps":["trace[438062236] 'process raft request' (duration: 231.590611ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.809582Z","caller":"traceutil/trace.go:171","msg":"trace[477512497] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"232.621354ms","start":"2026-02-17T18:36:59.576946Z","end":"2026-02-17T18:36:59.809567Z","steps":["trace[477512497] 'process raft request' (duration: 231.55888ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:36:59.809703Z","caller":"traceutil/trace.go:171","msg":"trace[955051113] linearizableReadLoop","detail":"{readStateIndex:31; appliedIndex:30; }","duration":"238.791381ms","start":"2026-02-17T18:36:59.5709Z","end":"2026-02-17T18:36:59.809691Z","steps":["trace[955051113] 'read index received' (duration: 6.518356ms)","trace[955051113] 'applied index is now lower than readState.Index' (duration: 232.271665ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:36:59.809994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.34408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:36:59.810046Z","caller":"traceutil/trace.go:171","msg":"trace[528574932] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:34; }","duration":"233.419483ms","start":"2026-02-17T18:36:59.576614Z","end":"2026-02-17T18:36:59.810033Z","steps":["trace[528574932] 'agreement among raft nodes before linearized reading' (duration: 233.328161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:36:59.809995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.067158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-17T18:36:59.810165Z","caller":"traceutil/trace.go:171","msg":"trace[527031713] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:34; }","duration":"239.280713ms","start":"2026-02-17T18:36:59.570871Z","end":"2026-02-17T18:36:59.810151Z","steps":["trace[527031713] 'agreement among raft nodes before linearized reading' (duration: 238.947005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:36:59.810266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.18858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:36:59.810298Z","caller":"traceutil/trace.go:171","msg":"trace[1860955120] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:34; }","duration":"138.280882ms","start":"2026-02-17T18:36:59.672006Z","end":"2026-02-17T18:36:59.810287Z","steps":["trace[1860955120] 'agreement among raft nodes before linearized reading' (duration: 138.231351ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:00.056925Z","caller":"traceutil/trace.go:171","msg":"trace[119468581] linearizableReadLoop","detail":"{readStateIndex:44; appliedIndex:42; }","duration":"146.287361ms","start":"2026-02-17T18:36:59.91062Z","end":"2026-02-17T18:37:00.056907Z","steps":["trace[119468581] 'read index received' (duration: 137.136497ms)","trace[119468581] 'applied index is now lower than readState.Index' (duration: 9.149554ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:00.057351Z","caller":"traceutil/trace.go:171","msg":"trace[683155245] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"206.39894ms","start":"2026-02-17T18:36:59.850943Z","end":"2026-02-17T18:37:00.057342Z","steps":["trace[683155245] 'process raft request' (duration: 196.734344ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:00.057871Z","caller":"traceutil/trace.go:171","msg":"trace[496384424] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"205.09509ms","start":"2026-02-17T18:36:59.852768Z","end":"2026-02-17T18:37:00.057863Z","steps":["trace[496384424] 'process raft request' (duration: 204.098998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:00.058743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.137732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:37:00.058787Z","caller":"traceutil/trace.go:171","msg":"trace[755251479] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:40; }","duration":"148.279174ms","start":"2026-02-17T18:36:59.910498Z","end":"2026-02-17T18:37:00.058778Z","steps":["trace[755251479] 'agreement among raft nodes before linearized reading' (duration: 148.156062ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:00.216277Z","caller":"traceutil/trace.go:171","msg":"trace[1402707238] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"152.186832ms","start":"2026-02-17T18:37:00.064064Z","end":"2026-02-17T18:37:00.216251Z","steps":["trace[1402707238] 'process raft request' (duration: 140.653644ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:00.217206Z","caller":"traceutil/trace.go:171","msg":"trace[1142268028] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"152.627682ms","start":"2026-02-17T18:37:00.064564Z","end":"2026-02-17T18:37:00.217191Z","steps":["trace[1142268028] 'process raft request' (duration: 151.027406ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:00.217585Z","caller":"traceutil/trace.go:171","msg":"trace[1482545380] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"152.910168ms","start":"2026-02-17T18:37:00.064665Z","end":"2026-02-17T18:37:00.217575Z","steps":["trace[1482545380] 'process raft request' (duration: 150.991555ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:00.218011Z","caller":"traceutil/trace.go:171","msg":"trace[585073194] linearizableReadLoop","detail":"{readStateIndex:48; appliedIndex:45; }","duration":"150.791899ms","start":"2026-02-17T18:37:00.064912Z","end":"2026-02-17T18:37:00.215704Z","steps":["trace[585073194] 'read index received' (duration: 57.873379ms)","trace[585073194] 'applied index is now lower than readState.Index' (duration: 92.91706ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:00.218529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.582191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:37:00.218592Z","caller":"traceutil/trace.go:171","msg":"trace[787556203] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:44; }","duration":"153.692964ms","start":"2026-02-17T18:37:00.064885Z","end":"2026-02-17T18:37:00.218578Z","steps":["trace[787556203] 'agreement among raft nodes before linearized reading' (duration: 153.51777ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:00.408495Z","caller":"traceutil/trace.go:171","msg":"trace[787433580] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"149.566292ms","start":"2026-02-17T18:37:00.258869Z","end":"2026-02-17T18:37:00.408436Z","steps":["trace[787433580] 'process raft request' (duration: 81.919805ms)","trace[787433580] 'compare' (duration: 66.947792ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:00.411073Z","caller":"traceutil/trace.go:171","msg":"trace[744397904] linearizableReadLoop","detail":"{readStateIndex:53; appliedIndex:52; }","duration":"100.204644ms","start":"2026-02-17T18:37:00.310851Z","end":"2026-02-17T18:37:00.411056Z","steps":["trace[744397904] 'read index received' (duration: 29.950148ms)","trace[744397904] 'applied index is now lower than readState.Index' (duration: 70.252056ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:00.411368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.456529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:37:00.411551Z","caller":"traceutil/trace.go:171","msg":"trace[644033492] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:49; }","duration":"100.593372ms","start":"2026-02-17T18:37:00.310811Z","end":"2026-02-17T18:37:00.411404Z","steps":["trace[644033492] 'agreement among raft nodes before linearized reading' (duration: 100.354337ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:00.411945Z","caller":"traceutil/trace.go:171","msg":"trace[480207709] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"100.662594ms","start":"2026-02-17T18:37:00.311021Z","end":"2026-02-17T18:37:00.411684Z","steps":["trace[480207709] 'process raft request' (duration: 99.025498ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:00.566524Z","caller":"traceutil/trace.go:171","msg":"trace[245262849] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"150.468633ms","start":"2026-02-17T18:37:00.416026Z","end":"2026-02-17T18:37:00.566495Z","steps":["trace[245262849] 'process raft request' (duration: 103.076177ms)","trace[245262849] 'compare' (duration: 46.916515ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:00.567357Z","caller":"traceutil/trace.go:171","msg":"trace[1650639452] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"132.888082ms","start":"2026-02-17T18:37:00.434425Z","end":"2026-02-17T18:37:00.567313Z","steps":["trace[1650639452] 'process raft request' (duration: 132.677037ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:00.567952Z","caller":"traceutil/trace.go:171","msg":"trace[1951566639] linearizableReadLoop","detail":"{readStateIndex:56; appliedIndex:55; }","duration":"134.928507ms","start":"2026-02-17T18:37:00.433014Z","end":"2026-02-17T18:37:00.567942Z","steps":["trace[1951566639] 'read index received' (duration: 86.101229ms)","trace[1951566639] 'applied index is now lower than readState.Index' (duration: 48.826268ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:00.569708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.663426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:37:00.569826Z","caller":"traceutil/trace.go:171","msg":"trace[1211626714] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:53; }","duration":"136.82741ms","start":"2026-02-17T18:37:00.432972Z","end":"2026-02-17T18:37:00.5698Z","steps":["trace[1211626714] 'agreement among raft nodes before linearized reading' (duration: 135.142962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:00.893635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.19065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-17T18:37:00.89396Z","caller":"traceutil/trace.go:171","msg":"trace[9465347] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"259.39785ms","start":"2026-02-17T18:37:00.634526Z","end":"2026-02-17T18:37:00.893924Z","steps":["trace[9465347] 'process raft request' (duration: 259.353469ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:00.894558Z","caller":"traceutil/trace.go:171","msg":"trace[1262778325] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"284.312257ms","start":"2026-02-17T18:37:00.610232Z","end":"2026-02-17T18:37:00.894544Z","steps":["trace[1262778325] 'process raft request' (duration: 100.925629ms)","trace[1262778325] 'compare' (duration: 182.027587ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:00.896069Z","caller":"traceutil/trace.go:171","msg":"trace[2081501171] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"285.272068ms","start":"2026-02-17T18:37:00.610782Z","end":"2026-02-17T18:37:00.896054Z","steps":["trace[2081501171] 'process raft request' (duration: 283.005957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:01.218688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.578828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-17T18:37:01.2191Z","caller":"traceutil/trace.go:171","msg":"trace[1898166969] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"314.713994ms","start":"2026-02-17T18:37:00.904363Z","end":"2026-02-17T18:37:01.219077Z","steps":["trace[1898166969] 'process raft request' (duration: 123.617785ms)","trace[1898166969] 'compare' (duration: 190.399823ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:01.219205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:00.904338Z","time spent":"314.801716ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1244,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:37:01.21942Z","caller":"traceutil/trace.go:171","msg":"trace[1061386123] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"314.862158ms","start":"2026-02-17T18:37:00.904548Z","end":"2026-02-17T18:37:01.219411Z","steps":["trace[1061386123] 'process raft request' (duration: 314.270914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:01.219466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:00.904535Z","time spent":"314.907578ms","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":702,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T18:37:01.219717Z","caller":"traceutil/trace.go:171","msg":"trace[694731087] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"313.546397ms","start":"2026-02-17T18:37:00.906161Z","end":"2026-02-17T18:37:01.219708Z","steps":["trace[694731087] 'process raft request' (duration: 312.709188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:01.21976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:00.906155Z","time spent":"313.585799ms","remote":"127.0.0.1:33350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T18:37:01.431861Z","caller":"traceutil/trace.go:171","msg":"trace[476213161] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"126.921948ms","start":"2026-02-17T18:37:01.304908Z","end":"2026-02-17T18:37:01.43183Z","steps":["trace[476213161] 'process raft request' (duration: 126.814286ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:01.432314Z","caller":"traceutil/trace.go:171","msg":"trace[1116046606] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"180.139055ms","start":"2026-02-17T18:37:01.252133Z","end":"2026-02-17T18:37:01.432272Z","steps":["trace[1116046606] 'process raft request' (duration: 142.627278ms)","trace[1116046606] 'compare' (duration: 36.32207ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:01.432648Z","caller":"traceutil/trace.go:171","msg":"trace[1607657103] linearizableReadLoop","detail":"{readStateIndex:69; appliedIndex:68; }","duration":"127.735947ms","start":"2026-02-17T18:37:01.304897Z","end":"2026-02-17T18:37:01.432633Z","steps":["trace[1607657103] 'read index received' (duration: 89.875823ms)","trace[1607657103] 'applied index is now lower than readState.Index' (duration: 37.858674ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:01.433644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.715128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:37:01.4338Z","caller":"traceutil/trace.go:171","msg":"trace[807251024] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:0; response_revision:66; }","duration":"128.917273ms","start":"2026-02-17T18:37:01.304868Z","end":"2026-02-17T18:37:01.433785Z","steps":["trace[807251024] 'agreement among raft nodes before linearized reading' (duration: 128.690068ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:01.631352Z","caller":"traceutil/trace.go:171","msg":"trace[1651158846] linearizableReadLoop","detail":"{readStateIndex:74; appliedIndex:71; }","duration":"154.035403ms","start":"2026-02-17T18:37:01.477286Z","end":"2026-02-17T18:37:01.631322Z","steps":["trace[1651158846] 'read index received' (duration: 151.791512ms)","trace[1651158846] 'applied index is now lower than readState.Index' (duration: 2.242501ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:01.631544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.224737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T18:37:01.631581Z","caller":"traceutil/trace.go:171","msg":"trace[611358569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:70; }","duration":"154.335599ms","start":"2026-02-17T18:37:01.477235Z","end":"2026-02-17T18:37:01.63157Z","steps":["trace[611358569] 'agreement among raft nodes before linearized reading' (duration: 154.192896ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:01.631996Z","caller":"traceutil/trace.go:171","msg":"trace[1203529171] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"193.672076ms","start":"2026-02-17T18:37:01.438307Z","end":"2026-02-17T18:37:01.631979Z","steps":["trace[1203529171] 'process raft request' (duration: 192.903529ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:01.632333Z","caller":"traceutil/trace.go:171","msg":"trace[1079211791] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"194.178397ms","start":"2026-02-17T18:37:01.438145Z","end":"2026-02-17T18:37:01.632323Z","steps":["trace[1079211791] 'process raft request' (duration: 190.914914ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:01.63331Z","caller":"traceutil/trace.go:171","msg":"trace[143289806] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"169.536428ms","start":"2026-02-17T18:37:01.463746Z","end":"2026-02-17T18:37:01.633283Z","steps":["trace[143289806] 'process raft request' (duration: 167.512523ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:07.225136Z","caller":"traceutil/trace.go:171","msg":"trace[1626346277] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:326; }","duration":"105.552632ms","start":"2026-02-17T18:37:07.119516Z","end":"2026-02-17T18:37:07.225068Z","steps":["trace[1626346277] 'read index received' (duration: 104.605751ms)","trace[1626346277] 'applied index is now lower than readState.Index' (duration: 943.17µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:07.225323Z","caller":"traceutil/trace.go:171","msg":"trace[1252432522] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"105.85128ms","start":"2026-02-17T18:37:07.119444Z","end":"2026-02-17T18:37:07.225296Z","steps":["trace[1252432522] 'process raft request' (duration: 104.761445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:07.225511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.967452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-17T18:37:07.225946Z","caller":"traceutil/trace.go:171","msg":"trace[124182687] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:315; }","duration":"106.458323ms","start":"2026-02-17T18:37:07.119476Z","end":"2026-02-17T18:37:07.225935Z","steps":["trace[124182687] 'agreement among raft nodes before linearized reading' (duration: 105.90175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:09.058089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.244036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-17T18:37:09.058411Z","caller":"traceutil/trace.go:171","msg":"trace[599578510] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:334; }","duration":"162.674285ms","start":"2026-02-17T18:37:08.895701Z","end":"2026-02-17T18:37:09.058376Z","steps":["trace[599578510] 'range keys from in-memory index tree' (duration: 162.009801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:18.306204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.237486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-17T18:37:18.306984Z","caller":"traceutil/trace.go:171","msg":"trace[828169985] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:420; }","duration":"124.041074ms","start":"2026-02-17T18:37:18.182921Z","end":"2026-02-17T18:37:18.306962Z","steps":["trace[828169985] 'agreement among raft nodes before linearized reading' (duration: 33.65676ms)","trace[828169985] 'range keys from in-memory index tree' (duration: 89.481814ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:19.533168Z","caller":"traceutil/trace.go:171","msg":"trace[1052036979] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"108.271452ms","start":"2026-02-17T18:37:19.424866Z","end":"2026-02-17T18:37:19.533137Z","steps":["trace[1052036979] 'read index received' (duration: 107.870944ms)","trace[1052036979] 'applied index is now lower than readState.Index' (duration: 396.728µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:19.533388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.459957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T18:37:19.533438Z","caller":"traceutil/trace.go:171","msg":"trace[1561019925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:450; }","duration":"108.61477ms","start":"2026-02-17T18:37:19.424799Z","end":"2026-02-17T18:37:19.533414Z","steps":["trace[1561019925] 'agreement among raft nodes before linearized reading' (duration: 108.458987ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:19.533752Z","caller":"traceutil/trace.go:171","msg":"trace[908133605] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"128.33266ms","start":"2026-02-17T18:37:19.405411Z","end":"2026-02-17T18:37:19.533743Z","steps":["trace[908133605] 'process raft request' (duration: 127.44122ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:20.240964Z","caller":"traceutil/trace.go:171","msg":"trace[1957141962] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"697.79914ms","start":"2026-02-17T18:37:19.543143Z","end":"2026-02-17T18:37:20.240942Z","steps":["trace[1957141962] 'process raft request' (duration: 697.519684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:20.241119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:19.538624Z","time spent":"702.409683ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:37:27.708035Z","caller":"traceutil/trace.go:171","msg":"trace[205449149] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"143.021827ms","start":"2026-02-17T18:37:27.564938Z","end":"2026-02-17T18:37:27.70796Z","steps":["trace[205449149] 'process raft request' (duration: 142.681899ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:34.4615Z","caller":"traceutil/trace.go:171","msg":"trace[1025714162] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"114.908061ms","start":"2026-02-17T18:37:34.346564Z","end":"2026-02-17T18:37:34.461472Z","steps":["trace[1025714162] 'process raft request' (duration: 112.545748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:35.064339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540728042675333,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T18:37:35.325875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.413618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T18:37:35.326158Z","caller":"traceutil/trace.go:171","msg":"trace[1028519273] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:539; }","duration":"762.600185ms","start":"2026-02-17T18:37:34.563537Z","end":"2026-02-17T18:37:35.326138Z","steps":["trace[1028519273] 'read index received' (duration: 639.813218ms)","trace[1028519273] 'applied index is now lower than readState.Index' (duration: 122.785507ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:35.326313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.779609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:37:35.326372Z","caller":"traceutil/trace.go:171","msg":"trace[1197288166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"762.877392ms","start":"2026-02-17T18:37:34.563485Z","end":"2026-02-17T18:37:35.326363Z","steps":["trace[1197288166] 'agreement among raft nodes before linearized reading' (duration: 762.7755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:35.326431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:34.563463Z","time spent":"762.958793ms","remote":"127.0.0.1:33074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T18:37:35.326778Z","caller":"traceutil/trace.go:171","msg":"trace[68500616] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"859.657418ms","start":"2026-02-17T18:37:34.467111Z","end":"2026-02-17T18:37:35.326768Z","steps":["trace[68500616] 'process raft request' (duration: 736.228207ms)","trace[68500616] 'compare' (duration: 121.658641ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:35.326856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:34.467084Z","time spent":"859.730949ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:37:35.32702Z","caller":"traceutil/trace.go:171","msg":"trace[688018209] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"858.651235ms","start":"2026-02-17T18:37:34.468361Z","end":"2026-02-17T18:37:35.327012Z","steps":["trace[688018209] 'process raft request' (duration: 857.703234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:35.327093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:34.468354Z","time spent":"858.699886ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:37:36.765596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.564742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:37:36.765709Z","caller":"traceutil/trace.go:171","msg":"trace[163693187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"206.721217ms","start":"2026-02-17T18:37:36.558952Z","end":"2026-02-17T18:37:36.765674Z","steps":["trace[163693187] 'range keys from in-memory index tree' (duration: 206.46802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:38.99944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540728042675351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T18:37:39.202485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.529249ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T18:37:39.20259Z","caller":"traceutil/trace.go:171","msg":"trace[1138290184] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"703.292883ms","start":"2026-02-17T18:37:38.499277Z","end":"2026-02-17T18:37:39.20257Z","steps":["trace[1138290184] 'read index received' (duration: 532.573618ms)","trace[1138290184] 'applied index is now lower than readState.Index' (duration: 170.717875ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:39.202697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.400815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T18:37:39.202732Z","caller":"traceutil/trace.go:171","msg":"trace[1147430009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"703.473786ms","start":"2026-02-17T18:37:38.499234Z","end":"2026-02-17T18:37:39.202708Z","steps":["trace[1147430009] 'agreement among raft nodes before linearized reading' (duration: 703.379464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:39.202761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:38.499214Z","time spent":"703.541108ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T18:37:39.527109Z","caller":"traceutil/trace.go:171","msg":"trace[654177210] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"176.544024ms","start":"2026-02-17T18:37:39.350535Z","end":"2026-02-17T18:37:39.527079Z","steps":["trace[654177210] 'read index received' (duration: 176.303069ms)","trace[654177210] 'applied index is now lower than readState.Index' (duration: 239.485µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:39.527398Z","caller":"traceutil/trace.go:171","msg":"trace[694979575] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"319.348305ms","start":"2026-02-17T18:37:39.20804Z","end":"2026-02-17T18:37:39.527389Z","steps":["trace[694979575] 'process raft request' (duration: 318.824803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:39.527505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:39.208014Z","time spent":"319.403215ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:37:39.527655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.120147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-17T18:37:39.527673Z","caller":"traceutil/trace.go:171","msg":"trace[1587510756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"177.362872ms","start":"2026-02-17T18:37:39.350305Z","end":"2026-02-17T18:37:39.527668Z","steps":["trace[1587510756] 'agreement among raft nodes before linearized reading' (duration: 177.321601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:39.527762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.173979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T18:37:39.527779Z","caller":"traceutil/trace.go:171","msg":"trace[1256407431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"177.19828ms","start":"2026-02-17T18:37:39.350574Z","end":"2026-02-17T18:37:39.527772Z","steps":["trace[1256407431] 'agreement among raft nodes before linearized reading' (duration: 177.157188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:39.991094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.462945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T18:37:39.991282Z","caller":"traceutil/trace.go:171","msg":"trace[778954395] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"431.46429ms","start":"2026-02-17T18:37:39.559755Z","end":"2026-02-17T18:37:39.99122Z","steps":["trace[778954395] 'read index received' (duration: 28.000916ms)","trace[778954395] 'applied index is now lower than readState.Index' (duration: 403.446383ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:39.991579Z","caller":"traceutil/trace.go:171","msg":"trace[885205076] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"459.463954ms","start":"2026-02-17T18:37:39.532097Z","end":"2026-02-17T18:37:39.991561Z","steps":["trace[885205076] 'process raft request' (duration: 153.405117ms)","trace[885205076] 'compare' (duration: 305.169148ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:39.991663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:39.532092Z","time spent":"459.528497ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:37:39.992406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.649916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:37:39.99246Z","caller":"traceutil/trace.go:171","msg":"trace[1251460359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"432.705087ms","start":"2026-02-17T18:37:39.55974Z","end":"2026-02-17T18:37:39.992445Z","steps":["trace[1251460359] 'agreement among raft nodes before linearized reading' (duration: 432.614315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:39.992495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:39.559672Z","time spent":"432.81588ms","remote":"127.0.0.1:33074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T18:37:39.992897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.679934ms","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-17T18:37:39.992946Z","caller":"traceutil/trace.go:171","msg":"trace[1133660395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"209.767356ms","start":"2026-02-17T18:37:39.783166Z","end":"2026-02-17T18:37:39.992933Z","steps":["trace[1133660395] 'agreement among raft nodes before linearized reading' (duration: 209.611633ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:40.215049Z","caller":"traceutil/trace.go:171","msg":"trace[639387384] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"216.534018ms","start":"2026-02-17T18:37:39.998488Z","end":"2026-02-17T18:37:40.215022Z","steps":["trace[639387384] 'process raft request' (duration: 157.376176ms)","trace[639387384] 'compare' (duration: 59.05989ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:45.347307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342507263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T18:37:45.347389Z","caller":"traceutil/trace.go:171","msg":"trace[1252155255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"1.342638735s","start":"2026-02-17T18:37:44.004731Z","end":"2026-02-17T18:37:45.34737Z","steps":["trace[1252155255] 'range keys from in-memory index tree' (duration: 1.342365729s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:45.347419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:44.004706Z","time spent":"1.342708117s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T18:37:45.347576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316094183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kwt7n\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-17T18:37:45.347596Z","caller":"traceutil/trace.go:171","msg":"trace[746915865] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kwt7n; range_end:; response_count:1; response_revision:543; }","duration":"1.316158374s","start":"2026-02-17T18:37:44.031432Z","end":"2026-02-17T18:37:45.34759Z","steps":["trace[746915865] 'range keys from in-memory index tree' (duration: 1.31597992s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:45.347616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:44.031407Z","time spent":"1.316201125s","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-kwt7n\" "} {"level":"warn","ts":"2026-02-17T18:37:45.347793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121370082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-17T18:37:45.347811Z","caller":"traceutil/trace.go:171","msg":"trace[2042890133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"1.121424994s","start":"2026-02-17T18:37:44.22638Z","end":"2026-02-17T18:37:45.347805Z","steps":["trace[2042890133] 'range keys from in-memory index tree' (duration: 1.121298451s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:45.347826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:44.226357Z","time spent":"1.121466024s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-17T18:37:45.6427Z","caller":"traceutil/trace.go:171","msg":"trace[1385101384] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"288.079836ms","start":"2026-02-17T18:37:45.354591Z","end":"2026-02-17T18:37:45.642671Z","steps":["trace[1385101384] 'process raft request' (duration: 287.903442ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:37:45.963168Z","caller":"traceutil/trace.go:171","msg":"trace[2102921569] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:564; }","duration":"412.892885ms","start":"2026-02-17T18:37:45.550247Z","end":"2026-02-17T18:37:45.96314Z","steps":["trace[2102921569] 'read index received' (duration: 92.348003ms)","trace[2102921569] 'applied index is now lower than readState.Index' (duration: 320.543942ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:45.963465Z","caller":"traceutil/trace.go:171","msg":"trace[475628114] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"607.788248ms","start":"2026-02-17T18:37:45.355664Z","end":"2026-02-17T18:37:45.963452Z","steps":["trace[475628114] 'process raft request' (duration: 607.244416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:45.963585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:45.355658Z","time spent":"607.83608ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:37:45.963949Z","caller":"traceutil/trace.go:171","msg":"trace[1754119761] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"599.631546ms","start":"2026-02-17T18:37:45.364306Z","end":"2026-02-17T18:37:45.963937Z","steps":["trace[1754119761] 'process raft request' (duration: 598.777127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:45.96401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:45.364289Z","time spent":"599.688798ms","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:37:45.964184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.932188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T18:37:45.964224Z","caller":"traceutil/trace.go:171","msg":"trace[365126204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:546; }","duration":"413.970529ms","start":"2026-02-17T18:37:45.550239Z","end":"2026-02-17T18:37:45.96421Z","steps":["trace[365126204] 'agreement among raft nodes before linearized reading' (duration: 413.882637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:45.964256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:45.550143Z","time spent":"414.107052ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T18:37:45.982593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.903236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:37:45.982687Z","caller":"traceutil/trace.go:171","msg":"trace[149818358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"422.045079ms","start":"2026-02-17T18:37:45.560619Z","end":"2026-02-17T18:37:45.982664Z","steps":["trace[149818358] 'agreement among raft nodes before linearized reading' (duration: 421.884286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:45.982725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:45.560605Z","time spent":"422.114221ms","remote":"127.0.0.1:33074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T18:37:45.982966Z","caller":"traceutil/trace.go:171","msg":"trace[1640125862] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"134.703529ms","start":"2026-02-17T18:37:45.848256Z","end":"2026-02-17T18:37:45.982959Z","steps":["trace[1640125862] 'process raft request' (duration: 134.129966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:45.983095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.375626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-17T18:37:45.983112Z","caller":"traceutil/trace.go:171","msg":"trace[1779444773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"375.436837ms","start":"2026-02-17T18:37:45.607671Z","end":"2026-02-17T18:37:45.983108Z","steps":["trace[1779444773] 'agreement among raft nodes before linearized reading' (duration: 375.357115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:45.98313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:45.607652Z","time spent":"375.474609ms","remote":"127.0.0.1:33282","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-17T18:37:48.775145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540728042675447,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T18:37:48.820866Z","caller":"traceutil/trace.go:171","msg":"trace[135917563] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:593; }","duration":"577.368899ms","start":"2026-02-17T18:37:48.243194Z","end":"2026-02-17T18:37:48.820562Z","steps":["trace[135917563] 'read index received' (duration: 563.683812ms)","trace[135917563] 'applied index is now lower than readState.Index' (duration: 13.684217ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:48.822151Z","caller":"traceutil/trace.go:171","msg":"trace[1262579188] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"429.109876ms","start":"2026-02-17T18:37:48.393028Z","end":"2026-02-17T18:37:48.822138Z","steps":["trace[1262579188] 'process raft request' (duration: 426.756754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:48.822254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:48.392993Z","time spent":"429.181217ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T18:37:48.822321Z","caller":"traceutil/trace.go:171","msg":"trace[1647552108] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"428.570644ms","start":"2026-02-17T18:37:48.393743Z","end":"2026-02-17T18:37:48.822314Z","steps":["trace[1647552108] 'process raft request' (duration: 426.376876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:48.823053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.846504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:37:48.823089Z","caller":"traceutil/trace.go:171","msg":"trace[1071962505] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:576; }","duration":"579.902895ms","start":"2026-02-17T18:37:48.243173Z","end":"2026-02-17T18:37:48.823076Z","steps":["trace[1071962505] 'agreement among raft nodes before linearized reading' (duration: 579.821793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:48.823106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:48.243166Z","time spent":"579.936426ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-02-17T18:37:48.823178Z","caller":"traceutil/trace.go:171","msg":"trace[607029698] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"400.530748ms","start":"2026-02-17T18:37:48.422641Z","end":"2026-02-17T18:37:48.823172Z","steps":["trace[607029698] 'process raft request' (duration: 397.663364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:48.823208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:48.422632Z","time spent":"400.561009ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T18:37:48.82462Z","caller":"traceutil/trace.go:171","msg":"trace[1814108925] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"203.095747ms","start":"2026-02-17T18:37:48.621516Z","end":"2026-02-17T18:37:48.824612Z","steps":["trace[1814108925] 'process raft request' (duration: 199.011066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:48.824929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.126571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:37:48.824954Z","caller":"traceutil/trace.go:171","msg":"trace[1459963604] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:576; }","duration":"580.159321ms","start":"2026-02-17T18:37:48.244783Z","end":"2026-02-17T18:37:48.824942Z","steps":["trace[1459963604] 'agreement among raft nodes before linearized reading' (duration: 580.10598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:48.824969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:48.244776Z","time spent":"580.188942ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-17T18:37:48.825072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.038593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:37:48.825092Z","caller":"traceutil/trace.go:171","msg":"trace[1764691896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"262.108315ms","start":"2026-02-17T18:37:48.562975Z","end":"2026-02-17T18:37:48.825083Z","steps":["trace[1764691896] 'agreement among raft nodes before linearized reading' (duration: 262.062934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:48.825224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.862814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:37:48.825244Z","caller":"traceutil/trace.go:171","msg":"trace[1742623965] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:576; }","duration":"579.897596ms","start":"2026-02-17T18:37:48.245341Z","end":"2026-02-17T18:37:48.825238Z","steps":["trace[1742623965] 'agreement among raft nodes before linearized reading' (duration: 579.841784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:48.825261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:48.245334Z","time spent":"579.920496ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-17T18:37:48.825453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:48.393729Z","time spent":"428.610365ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T18:37:56.48426Z","caller":"traceutil/trace.go:171","msg":"trace[1258543345] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:681; }","duration":"437.162857ms","start":"2026-02-17T18:37:56.047069Z","end":"2026-02-17T18:37:56.484232Z","steps":["trace[1258543345] 'read index received' (duration: 436.885271ms)","trace[1258543345] 'applied index is now lower than readState.Index' (duration: 276.526µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:56.484606Z","caller":"traceutil/trace.go:171","msg":"trace[473565370] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"749.987215ms","start":"2026-02-17T18:37:55.734586Z","end":"2026-02-17T18:37:56.484573Z","steps":["trace[473565370] 'process raft request' (duration: 749.412502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:56.485457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:55.733461Z","time spent":"751.930449ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:37:56.484682Z","caller":"traceutil/trace.go:171","msg":"trace[1320162790] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"747.610303ms","start":"2026-02-17T18:37:55.737064Z","end":"2026-02-17T18:37:56.484675Z","steps":["trace[1320162790] 'process raft request' (duration: 747.126671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:56.48583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:55.737056Z","time spent":"748.669526ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:37:56.48488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.73921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-17T18:37:56.486557Z","caller":"traceutil/trace.go:171","msg":"trace[587474418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"439.501809ms","start":"2026-02-17T18:37:56.047039Z","end":"2026-02-17T18:37:56.486541Z","steps":["trace[587474418] 'agreement among raft nodes before linearized reading' (duration: 437.656908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:56.48664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:56.047019Z","time spent":"439.609442ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T18:37:56.985968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540728042675626,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T18:37:58.000984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.88994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T18:37:58.001198Z","caller":"traceutil/trace.go:171","msg":"trace[1483043024] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.730797498s","start":"2026-02-17T18:37:56.270384Z","end":"2026-02-17T18:37:58.001182Z","steps":["trace[1483043024] 'process raft request' (duration: 1.161615931s)","trace[1483043024] 'compare' (duration: 568.528062ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:58.001212Z","caller":"traceutil/trace.go:171","msg":"trace[924494422] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:683; }","duration":"1.515837235s","start":"2026-02-17T18:37:56.485361Z","end":"2026-02-17T18:37:58.001198Z","steps":["trace[924494422] 'read index received' (duration: 946.620157ms)","trace[924494422] 'applied index is now lower than readState.Index' (duration: 569.216078ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:58.001261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:56.270359Z","time spent":"1.73088065s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:37:58.001354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.725211423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:37:58.001382Z","caller":"traceutil/trace.go:171","msg":"trace[233218622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"1.725238434s","start":"2026-02-17T18:37:56.276138Z","end":"2026-02-17T18:37:58.001376Z","steps":["trace[233218622] 'agreement among raft nodes before linearized reading' (duration: 1.725197443s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:58.001396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:56.276101Z","time spent":"1.725292235s","remote":"127.0.0.1:33074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T18:37:58.001491Z","caller":"traceutil/trace.go:171","msg":"trace[733766315] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.507897988s","start":"2026-02-17T18:37:56.493587Z","end":"2026-02-17T18:37:58.001485Z","steps":["trace[733766315] 'process raft request' (duration: 1.50751463s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:58.001532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:56.493569Z","time spent":"1.507932119s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:37:58.001685Z","caller":"traceutil/trace.go:171","msg":"trace[1609512340] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.349394047s","start":"2026-02-17T18:37:56.652272Z","end":"2026-02-17T18:37:58.001666Z","steps":["trace[1609512340] 'process raft request' (duration: 1.348887166s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:58.001708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:56.652212Z","time spent":"1.34948563s","remote":"127.0.0.1:32870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T18:37:58.001875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.722767579s","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-17T18:37:58.001892Z","caller":"traceutil/trace.go:171","msg":"trace[1176242688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:663; }","duration":"1.722801769s","start":"2026-02-17T18:37:56.279083Z","end":"2026-02-17T18:37:58.001885Z","steps":["trace[1176242688] 'agreement among raft nodes before linearized reading' (duration: 1.722733668s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:58.001905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:56.279078Z","time spent":"1.722824449s","remote":"127.0.0.1:33282","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-17T18:37:58.002914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.945626207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-17T18:37:58.002947Z","caller":"traceutil/trace.go:171","msg":"trace[2101948148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:663; }","duration":"1.945681259s","start":"2026-02-17T18:37:56.05726Z","end":"2026-02-17T18:37:58.002941Z","steps":["trace[2101948148] 'agreement among raft nodes before linearized reading' (duration: 1.945614498s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:58.002961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:56.057244Z","time spent":"1.94571307s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T18:37:58.003055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443876039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:37:58.00307Z","caller":"traceutil/trace.go:171","msg":"trace[692514825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"1.44394013s","start":"2026-02-17T18:37:56.559125Z","end":"2026-02-17T18:37:58.003065Z","steps":["trace[692514825] 'agreement among raft nodes before linearized reading' (duration: 1.443913139s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:58.003096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:56.559109Z","time spent":"1.443984281s","remote":"127.0.0.1:33078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T18:37:58.003706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.358664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kwt7n\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-17T18:37:58.003725Z","caller":"traceutil/trace.go:171","msg":"trace[312274101] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kwt7n; range_end:; response_count:1; response_revision:663; }","duration":"263.409905ms","start":"2026-02-17T18:37:57.74031Z","end":"2026-02-17T18:37:58.003719Z","steps":["trace[312274101] 'agreement among raft nodes before linearized reading' (duration: 263.331704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:58.007307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.942652971s","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-17T18:37:58.007387Z","caller":"traceutil/trace.go:171","msg":"trace[640214206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:663; }","duration":"1.942794114s","start":"2026-02-17T18:37:56.064577Z","end":"2026-02-17T18:37:58.007371Z","steps":["trace[640214206] 'agreement among raft nodes before linearized reading' (duration: 1.936837721s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:58.007427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:56.06456Z","time spent":"1.942858145s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T18:37:58.948121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.646199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T18:37:58.948206Z","caller":"traceutil/trace.go:171","msg":"trace[791007762] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"930.453687ms","start":"2026-02-17T18:37:58.017738Z","end":"2026-02-17T18:37:58.948192Z","steps":["trace[791007762] 'read index received' (duration: 111.692156ms)","trace[791007762] 'applied index is now lower than readState.Index' (duration: 818.760771ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:37:58.948403Z","caller":"traceutil/trace.go:171","msg":"trace[1136266896] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"930.834656ms","start":"2026-02-17T18:37:58.01756Z","end":"2026-02-17T18:37:58.948395Z","steps":["trace[1136266896] 'process raft request' (duration: 111.829579ms)","trace[1136266896] 'compare' (duration: 816.969361ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:58.948962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:58.017547Z","time spent":"930.906557ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:37:58.949181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.439559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:37:58.949221Z","caller":"traceutil/trace.go:171","msg":"trace[872113627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"931.49052ms","start":"2026-02-17T18:37:58.017723Z","end":"2026-02-17T18:37:58.949214Z","steps":["trace[872113627] 'agreement among raft nodes before linearized reading' (duration: 931.431568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:58.949467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:58.017718Z","time spent":"931.742625ms","remote":"127.0.0.1:33078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T18:37:59.024641Z","caller":"traceutil/trace.go:171","msg":"trace[370694793] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"999.989701ms","start":"2026-02-17T18:37:58.024626Z","end":"2026-02-17T18:37:59.024616Z","steps":["trace[370694793] 'process raft request' (duration: 999.940059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:59.024954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:58.024621Z","time spent":"1.000238926s","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:37:59.025321Z","caller":"traceutil/trace.go:171","msg":"trace[661836806] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.007450168s","start":"2026-02-17T18:37:58.01786Z","end":"2026-02-17T18:37:59.02531Z","steps":["trace[661836806] 'process raft request' (duration: 1.006425064s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:59.025371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:58.01784Z","time spent":"1.007505099s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:37:59.025464Z","caller":"traceutil/trace.go:171","msg":"trace[1660070904] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"1.003468699s","start":"2026-02-17T18:37:58.021989Z","end":"2026-02-17T18:37:59.025457Z","steps":["trace[1660070904] 'process raft request' (duration: 1.002436335s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:59.025503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:58.021974Z","time spent":"1.00350998s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:37:59.025651Z","caller":"traceutil/trace.go:171","msg":"trace[1759410898] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"1.001401473s","start":"2026-02-17T18:37:58.024243Z","end":"2026-02-17T18:37:59.025645Z","steps":["trace[1759410898] 'process raft request' (duration: 1.000233396s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:59.025687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:58.024234Z","time spent":"1.001438694s","remote":"127.0.0.1:32856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T18:37:59.027632Z","caller":"traceutil/trace.go:171","msg":"trace[632234866] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"1.003293204s","start":"2026-02-17T18:37:58.024328Z","end":"2026-02-17T18:37:59.027622Z","steps":["trace[632234866] 'process raft request' (duration: 1.000179415s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:59.027686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:58.024324Z","time spent":"1.003338175s","remote":"127.0.0.1:32856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T18:37:59.027802Z","caller":"traceutil/trace.go:171","msg":"trace[1445001530] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"1.003262883s","start":"2026-02-17T18:37:58.024532Z","end":"2026-02-17T18:37:59.027795Z","steps":["trace[1445001530] 'process raft request' (duration: 999.998341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:59.027841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:58.024527Z","time spent":"1.003298574s","remote":"127.0.0.1:32856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T18:37:59.433896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.727709ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T18:37:59.433988Z","caller":"traceutil/trace.go:171","msg":"trace[1091548085] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"485.751332ms","start":"2026-02-17T18:37:58.948222Z","end":"2026-02-17T18:37:59.433973Z","steps":["trace[1091548085] 'read index received' (duration: 81.372688ms)","trace[1091548085] 'applied index is now lower than readState.Index' (duration: 404.377564ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:37:59.434071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.662448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:37:59.434105Z","caller":"traceutil/trace.go:171","msg":"trace[119084822] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:670; }","duration":"660.762281ms","start":"2026-02-17T18:37:58.773336Z","end":"2026-02-17T18:37:59.434098Z","steps":["trace[119084822] 'agreement among raft nodes before linearized reading' (duration: 660.6862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:59.434125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:58.773314Z","time spent":"660.805732ms","remote":"127.0.0.1:33174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-17T18:37:59.434415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.997455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T18:37:59.434435Z","caller":"traceutil/trace.go:171","msg":"trace[1634624083] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:670; }","duration":"831.062187ms","start":"2026-02-17T18:37:58.603365Z","end":"2026-02-17T18:37:59.434427Z","steps":["trace[1634624083] 'agreement among raft nodes before linearized reading' (duration: 831.001395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:59.434449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:58.603344Z","time spent":"831.101188ms","remote":"127.0.0.1:33542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-17T18:37:59.435558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.931828ms","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-17T18:37:59.435575Z","caller":"traceutil/trace.go:171","msg":"trace[972477431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:670; }","duration":"943.982789ms","start":"2026-02-17T18:37:58.491588Z","end":"2026-02-17T18:37:59.43557Z","steps":["trace[972477431] 'agreement among raft nodes before linearized reading' (duration: 943.848726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:59.435591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:58.491567Z","time spent":"944.02088ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T18:37:59.4364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350859589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T18:37:59.43642Z","caller":"traceutil/trace.go:171","msg":"trace[426457492] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:670; }","duration":"1.35091226s","start":"2026-02-17T18:37:58.085503Z","end":"2026-02-17T18:37:59.436415Z","steps":["trace[426457492] 'agreement among raft nodes before linearized reading' (duration: 1.35087059s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:59.436477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:58.085481Z","time spent":"1.350989772s","remote":"127.0.0.1:33156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-17T18:37:59.436796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.529522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kwt7n\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-17T18:37:59.43683Z","caller":"traceutil/trace.go:171","msg":"trace[1462692780] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kwt7n; range_end:; response_count:1; response_revision:670; }","duration":"397.592582ms","start":"2026-02-17T18:37:59.039231Z","end":"2026-02-17T18:37:59.436824Z","steps":["trace[1462692780] 'agreement among raft nodes before linearized reading' (duration: 397.4911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:37:59.436857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:37:59.039213Z","time spent":"397.640814ms","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18274,"request content":"key:\"/registry/pods/kube-system/cilium-kwt7n\" "} {"level":"warn","ts":"2026-02-17T18:38:03.67784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.360639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:38:03.677922Z","caller":"traceutil/trace.go:171","msg":"trace[1795593038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"116.495142ms","start":"2026-02-17T18:38:03.561408Z","end":"2026-02-17T18:38:03.677903Z","steps":["trace[1795593038] 'range keys from in-memory index tree' (duration: 116.066482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:08.365808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.176262ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:38:08.365972Z","caller":"traceutil/trace.go:171","msg":"trace[302756602] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:736; }","duration":"456.379466ms","start":"2026-02-17T18:38:07.90957Z","end":"2026-02-17T18:38:08.365949Z","steps":["trace[302756602] 'range keys from in-memory index tree' (duration: 456.155591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:09.421289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10063458s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T18:38:09.421516Z","caller":"traceutil/trace.go:171","msg":"trace[704210177] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"1.363585074s","start":"2026-02-17T18:38:08.057913Z","end":"2026-02-17T18:38:09.421498Z","steps":["trace[704210177] 'read index received' (duration: 262.872673ms)","trace[704210177] 'applied index is now lower than readState.Index' (duration: 1.100711421s)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:38:09.422194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364266079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-17T18:38:09.42222Z","caller":"traceutil/trace.go:171","msg":"trace[894603263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:736; }","duration":"1.364333631s","start":"2026-02-17T18:38:08.057876Z","end":"2026-02-17T18:38:09.42221Z","steps":["trace[894603263] 'agreement among raft nodes before linearized reading' (duration: 1.364181998s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:09.422244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:08.057858Z","time spent":"1.364380612s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T18:38:09.422535Z","caller":"traceutil/trace.go:171","msg":"trace[1313070072] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"1.317232218s","start":"2026-02-17T18:38:08.105289Z","end":"2026-02-17T18:38:09.422521Z","steps":["trace[1313070072] 'process raft request' (duration: 1.316100893s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:09.422617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:08.105264Z","time spent":"1.317288759s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:38:09.464572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.516406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-17T18:38:09.464586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.830063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-17T18:38:09.464646Z","caller":"traceutil/trace.go:171","msg":"trace[618112878] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:737; }","duration":"378.650549ms","start":"2026-02-17T18:38:09.085977Z","end":"2026-02-17T18:38:09.464627Z","steps":["trace[618112878] 'agreement among raft nodes before linearized reading' (duration: 336.739763ms)","trace[618112878] 'range keys from in-memory index tree' (duration: 41.725332ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:38:09.464654Z","caller":"traceutil/trace.go:171","msg":"trace[2000150683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:737; }","duration":"480.946985ms","start":"2026-02-17T18:38:08.983689Z","end":"2026-02-17T18:38:09.464636Z","steps":["trace[2000150683] 'agreement among raft nodes before linearized reading' (duration: 439.037038ms)","trace[2000150683] 'range keys from in-memory index tree' (duration: 41.749343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:38:09.464681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:09.085954Z","time spent":"378.720421ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T18:38:09.46469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:08.98367Z","time spent":"481.014297ms","remote":"127.0.0.1:33282","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-17T18:38:09.464828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.829153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T18:38:09.464845Z","caller":"traceutil/trace.go:171","msg":"trace[76051229] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:737; }","duration":"557.883473ms","start":"2026-02-17T18:38:08.906955Z","end":"2026-02-17T18:38:09.464839Z","steps":["trace[76051229] 'agreement among raft nodes before linearized reading' (duration: 515.782343ms)","trace[76051229] 'count revisions from in-memory index tree' (duration: 42.068449ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:38:09.464867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:08.906936Z","time spent":"557.923654ms","remote":"127.0.0.1:33224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-17T18:38:09.464914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.198483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:38:09.464933Z","caller":"traceutil/trace.go:171","msg":"trace[314166429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"905.254794ms","start":"2026-02-17T18:38:08.559672Z","end":"2026-02-17T18:38:09.464927Z","steps":["trace[314166429] 'agreement among raft nodes before linearized reading' (duration: 862.832076ms)","trace[314166429] 'range keys from in-memory index tree' (duration: 42.390836ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:38:09.464947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:08.559651Z","time spent":"905.292835ms","remote":"127.0.0.1:33074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T18:38:09.465087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.512776ms","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":"warn","ts":"2026-02-17T18:38:09.465091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.466465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T18:38:09.465104Z","caller":"traceutil/trace.go:171","msg":"trace[1403597945] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:737; }","duration":"109.565978ms","start":"2026-02-17T18:38:09.35553Z","end":"2026-02-17T18:38:09.465096Z","steps":["trace[1403597945] 'agreement among raft nodes before linearized reading' (duration: 67.167911ms)","trace[1403597945] 'count revisions from in-memory index tree' (duration: 42.373146ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:38:09.465119Z","caller":"traceutil/trace.go:171","msg":"trace[1824748982] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:737; }","duration":"784.528447ms","start":"2026-02-17T18:38:08.680577Z","end":"2026-02-17T18:38:09.465106Z","steps":["trace[1824748982] 'agreement among raft nodes before linearized reading' (duration: 742.171991ms)","trace[1824748982] 'count revisions from in-memory index tree' (duration: 42.325125ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:38:09.465155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:08.680545Z","time spent":"784.603239ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-17T18:38:09.465208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.934674ms","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-17T18:38:09.465223Z","caller":"traceutil/trace.go:171","msg":"trace[1829881521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"377.975514ms","start":"2026-02-17T18:38:09.087242Z","end":"2026-02-17T18:38:09.465217Z","steps":["trace[1829881521] 'agreement among raft nodes before linearized reading' (duration: 335.465435ms)","trace[1829881521] 'range keys from in-memory index tree' (duration: 42.465108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:38:09.465245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:09.087232Z","time spent":"378.009865ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T18:38:15.962386Z","caller":"traceutil/trace.go:171","msg":"trace[27758541] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"401.54583ms","start":"2026-02-17T18:38:15.560814Z","end":"2026-02-17T18:38:15.962359Z","steps":["trace[27758541] 'read index received' (duration: 401.303435ms)","trace[27758541] 'applied index is now lower than readState.Index' (duration: 241.605µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:38:15.962517Z","caller":"traceutil/trace.go:171","msg":"trace[790245249] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"467.914623ms","start":"2026-02-17T18:38:15.494592Z","end":"2026-02-17T18:38:15.962506Z","steps":["trace[790245249] 'process raft request' (duration: 467.616766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:15.962682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:15.494578Z","time spent":"467.991445ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:38:15.962802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.761932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-17T18:38:15.962871Z","caller":"traceutil/trace.go:171","msg":"trace[1031638319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"395.863144ms","start":"2026-02-17T18:38:15.56699Z","end":"2026-02-17T18:38:15.962853Z","steps":["trace[1031638319] 'agreement among raft nodes before linearized reading' (duration: 395.67553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:15.962906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:15.56698Z","time spent":"395.917106ms","remote":"127.0.0.1:33282","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-17T18:38:15.96311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.564103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T18:38:15.963132Z","caller":"traceutil/trace.go:171","msg":"trace[2118081500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"393.585653ms","start":"2026-02-17T18:38:15.56954Z","end":"2026-02-17T18:38:15.963126Z","steps":["trace[2118081500] 'agreement among raft nodes before linearized reading' (duration: 393.519602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:15.963141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.328668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T18:38:15.96315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:15.569522Z","time spent":"393.624044ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T18:38:15.963164Z","caller":"traceutil/trace.go:171","msg":"trace[1340825183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"402.38287ms","start":"2026-02-17T18:38:15.560773Z","end":"2026-02-17T18:38:15.963156Z","steps":["trace[1340825183] 'agreement among raft nodes before linearized reading' (duration: 402.352279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:15.963186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:15.560754Z","time spent":"402.429341ms","remote":"127.0.0.1:33074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T18:38:15.96328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.779269ms","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-17T18:38:15.963299Z","caller":"traceutil/trace.go:171","msg":"trace[1780251671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"394.80987ms","start":"2026-02-17T18:38:15.568483Z","end":"2026-02-17T18:38:15.963293Z","steps":["trace[1780251671] 'agreement among raft nodes before linearized reading' (duration: 394.750588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:15.963318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:15.568477Z","time spent":"394.835141ms","remote":"127.0.0.1:33282","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-17T18:38:15.963107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.566576ms","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-17T18:38:15.963387Z","caller":"traceutil/trace.go:171","msg":"trace[1265515925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"343.870313ms","start":"2026-02-17T18:38:15.61951Z","end":"2026-02-17T18:38:15.963381Z","steps":["trace[1265515925] 'agreement among raft nodes before linearized reading' (duration: 343.505115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:15.963414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:15.619492Z","time spent":"343.915873ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-17T18:38:26.119951Z","caller":"traceutil/trace.go:171","msg":"trace[266906838] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"110.178002ms","start":"2026-02-17T18:38:26.009748Z","end":"2026-02-17T18:38:26.119926Z","steps":["trace[266906838] 'process raft request' (duration: 109.886855ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:38:26.277031Z","caller":"traceutil/trace.go:171","msg":"trace[859513389] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"146.577115ms","start":"2026-02-17T18:38:26.130422Z","end":"2026-02-17T18:38:26.276999Z","steps":["trace[859513389] 'process raft request' (duration: 146.512783ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:38:26.277991Z","caller":"traceutil/trace.go:171","msg":"trace[203301808] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"149.161542ms","start":"2026-02-17T18:38:26.128817Z","end":"2026-02-17T18:38:26.277978Z","steps":["trace[203301808] 'process raft request' (duration: 147.944354ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:38:26.278326Z","caller":"traceutil/trace.go:171","msg":"trace[1295749235] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"149.426188ms","start":"2026-02-17T18:38:26.128888Z","end":"2026-02-17T18:38:26.278315Z","steps":["trace[1295749235] 'process raft request' (duration: 148.006556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:28.874917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.874774ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T18:38:28.875013Z","caller":"traceutil/trace.go:171","msg":"trace[1400245268] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"745.952175ms","start":"2026-02-17T18:38:28.129045Z","end":"2026-02-17T18:38:28.874997Z","steps":["trace[1400245268] 'read index received' (duration: 443.914157ms)","trace[1400245268] 'applied index is now lower than readState.Index' (duration: 302.036558ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:38:28.875423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.364584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-17T18:38:28.875452Z","caller":"traceutil/trace.go:171","msg":"trace[230459021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"746.450475ms","start":"2026-02-17T18:38:28.128993Z","end":"2026-02-17T18:38:28.875444Z","steps":["trace[230459021] 'agreement among raft nodes before linearized reading' (duration: 746.319483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:28.875478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:28.128968Z","time spent":"746.503467ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T18:38:28.875714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.129006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T18:38:28.875742Z","caller":"traceutil/trace.go:171","msg":"trace[212749875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"668.158477ms","start":"2026-02-17T18:38:28.207577Z","end":"2026-02-17T18:38:28.875735Z","steps":["trace[212749875] 'agreement among raft nodes before linearized reading' (duration: 668.086995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:28.875761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:28.20749Z","time spent":"668.267438ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T18:38:28.876424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.44482ms","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-17T18:38:28.876457Z","caller":"traceutil/trace.go:171","msg":"trace[151912883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"590.488982ms","start":"2026-02-17T18:38:28.28596Z","end":"2026-02-17T18:38:28.876449Z","steps":["trace[151912883] 'agreement among raft nodes before linearized reading' (duration: 590.40704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:28.876477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:28.285957Z","time spent":"590.514702ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T18:38:28.876686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.876568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-17T18:38:28.876722Z","caller":"traceutil/trace.go:171","msg":"trace[272466806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"589.925159ms","start":"2026-02-17T18:38:28.286789Z","end":"2026-02-17T18:38:28.876714Z","steps":["trace[272466806] 'agreement among raft nodes before linearized reading' (duration: 589.851667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:28.876741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:28.286781Z","time spent":"589.955161ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T18:38:28.876923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.971863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-17T18:38:28.87695Z","caller":"traceutil/trace.go:171","msg":"trace[1259608974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"591.032114ms","start":"2026-02-17T18:38:28.285911Z","end":"2026-02-17T18:38:28.876944Z","steps":["trace[1259608974] 'agreement among raft nodes before linearized reading' (duration: 590.970113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:28.876973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:28.28589Z","time spent":"591.077905ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T18:38:29.065356Z","caller":"traceutil/trace.go:171","msg":"trace[1653647579] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"170.881928ms","start":"2026-02-17T18:38:28.894433Z","end":"2026-02-17T18:38:29.065315Z","steps":["trace[1653647579] 'process raft request' (duration: 168.087055ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:38:29.065843Z","caller":"traceutil/trace.go:171","msg":"trace[1639981513] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"171.199835ms","start":"2026-02-17T18:38:28.894632Z","end":"2026-02-17T18:38:29.065832Z","steps":["trace[1639981513] 'process raft request' (duration: 168.559056ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:38:29.067133Z","caller":"traceutil/trace.go:171","msg":"trace[1956822662] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"170.955139ms","start":"2026-02-17T18:38:28.896056Z","end":"2026-02-17T18:38:29.067011Z","steps":["trace[1956822662] 'process raft request' (duration: 168.702229ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:38:29.067771Z","caller":"traceutil/trace.go:171","msg":"trace[1921570679] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"170.856437ms","start":"2026-02-17T18:38:28.896896Z","end":"2026-02-17T18:38:29.067752Z","steps":["trace[1921570679] 'process raft request' (duration: 168.021904ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:38:41.388825Z","caller":"traceutil/trace.go:171","msg":"trace[1222233866] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"253.891009ms","start":"2026-02-17T18:38:41.134649Z","end":"2026-02-17T18:38:41.38854Z","steps":["trace[1222233866] 'read index received' (duration: 253.726856ms)","trace[1222233866] 'applied index is now lower than readState.Index' (duration: 160.443µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:38:41.388944Z","caller":"traceutil/trace.go:171","msg":"trace[473248784] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"386.392908ms","start":"2026-02-17T18:38:41.002543Z","end":"2026-02-17T18:38:41.388936Z","steps":["trace[473248784] 'process raft request' (duration: 385.743765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:41.389175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.829659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-17T18:38:41.389271Z","caller":"traceutil/trace.go:171","msg":"trace[170891053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:877; }","duration":"224.984692ms","start":"2026-02-17T18:38:41.164261Z","end":"2026-02-17T18:38:41.389245Z","steps":["trace[170891053] 'agreement among raft nodes before linearized reading' (duration: 224.715166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:41.389444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:41.002526Z","time spent":"386.45087ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:38:41.389573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.918831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-17T18:38:41.389609Z","caller":"traceutil/trace.go:171","msg":"trace[805270446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:877; }","duration":"254.990691ms","start":"2026-02-17T18:38:41.134608Z","end":"2026-02-17T18:38:41.389598Z","steps":["trace[805270446] 'agreement among raft nodes before linearized reading' (duration: 254.895759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:41.389832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.003124ms","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-17T18:38:41.389864Z","caller":"traceutil/trace.go:171","msg":"trace[1396930300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:877; }","duration":"192.047385ms","start":"2026-02-17T18:38:41.197805Z","end":"2026-02-17T18:38:41.389852Z","steps":["trace[1396930300] 'agreement among raft nodes before linearized reading' (duration: 191.966533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:41.390019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.472501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T18:38:41.390051Z","caller":"traceutil/trace.go:171","msg":"trace[1002903963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:877; }","duration":"195.541563ms","start":"2026-02-17T18:38:41.194501Z","end":"2026-02-17T18:38:41.390043Z","steps":["trace[1002903963] 'agreement among raft nodes before linearized reading' (duration: 195.447131ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:38:42.737664Z","caller":"traceutil/trace.go:171","msg":"trace[432114661] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"148.682505ms","start":"2026-02-17T18:38:42.588957Z","end":"2026-02-17T18:38:42.737639Z","steps":["trace[432114661] 'process raft request' (duration: 148.528011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:43.537821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.962534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T18:38:43.537926Z","caller":"traceutil/trace.go:171","msg":"trace[222916109] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:1; response_revision:882; }","duration":"756.135627ms","start":"2026-02-17T18:38:42.781769Z","end":"2026-02-17T18:38:43.537905Z","steps":["trace[222916109] 'range keys from in-memory index tree' (duration: 755.771799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:43.538005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:42.781748Z","time spent":"756.207979ms","remote":"127.0.0.1:33094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.195\" "} {"level":"warn","ts":"2026-02-17T18:38:43.538656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.795628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-17T18:38:43.538692Z","caller":"traceutil/trace.go:171","msg":"trace[2053116636] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:882; }","duration":"142.89723ms","start":"2026-02-17T18:38:43.395786Z","end":"2026-02-17T18:38:43.538683Z","steps":["trace[2053116636] 'range keys from in-memory index tree' (duration: 142.493061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:43.538933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.927039ms","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-17T18:38:43.538965Z","caller":"traceutil/trace.go:171","msg":"trace[74373499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:882; }","duration":"129.00727ms","start":"2026-02-17T18:38:43.40995Z","end":"2026-02-17T18:38:43.538957Z","steps":["trace[74373499] 'range keys from in-memory index tree' (duration: 128.830467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:43.539108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.370334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-17T18:38:43.539141Z","caller":"traceutil/trace.go:171","msg":"trace[402716817] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:882; }","duration":"119.461485ms","start":"2026-02-17T18:38:43.419668Z","end":"2026-02-17T18:38:43.53913Z","steps":["trace[402716817] 'range keys from in-memory index tree' (duration: 119.24223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:43.539287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.361551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-17T18:38:43.53932Z","caller":"traceutil/trace.go:171","msg":"trace[696052833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:882; }","duration":"118.428573ms","start":"2026-02-17T18:38:43.420882Z","end":"2026-02-17T18:38:43.53931Z","steps":["trace[696052833] 'range keys from in-memory index tree' (duration: 118.25142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:43.539484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.455391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T18:38:43.539515Z","caller":"traceutil/trace.go:171","msg":"trace[541066444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:882; }","duration":"117.499772ms","start":"2026-02-17T18:38:43.422007Z","end":"2026-02-17T18:38:43.539507Z","steps":["trace[541066444] 'range keys from in-memory index tree' (duration: 117.353899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:44.067654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540728042676132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T18:38:44.568284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540728042676132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T18:38:44.952089Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.402642831s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-17T18:38:44.952416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:43.549298Z","time spent":"1.403114291s","remote":"127.0.0.1:33094","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-17T18:38:45.567523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000168261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-17T18:38:45.567633Z","caller":"traceutil/trace.go:171","msg":"trace[1283826494] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000338974s","start":"2026-02-17T18:38:43.567264Z","end":"2026-02-17T18:38:45.567603Z","steps":["trace[1283826494] 'agreement among raft nodes before linearized reading' (duration: 2.000195061s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:45.567685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:43.567246Z","time spent":"2.000429756s","remote":"127.0.0.1:33074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T18:38:47.289223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:46.126826Z","time spent":"1.162389053s","remote":"127.0.0.1:38922","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-17T18:38:47.415494Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.463234039s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T18:38:47.415982Z","caller":"traceutil/trace.go:171","msg":"trace[299547074] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:918; }","duration":"3.848658049s","start":"2026-02-17T18:38:43.567304Z","end":"2026-02-17T18:38:47.415962Z","steps":["trace[299547074] 'read index received' (duration: 1.384985301s)","trace[299547074] 'applied index is now lower than readState.Index' (duration: 2.463671918s)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:38:47.416258Z","caller":"traceutil/trace.go:171","msg":"trace[816283699] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"3.86672442s","start":"2026-02-17T18:38:43.549523Z","end":"2026-02-17T18:38:47.416247Z","steps":["trace[816283699] 'process raft request' (duration: 3.866145927s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:47.416348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:43.54951Z","time spent":"3.866775311s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:38:47.41648Z","caller":"traceutil/trace.go:171","msg":"trace[125842796] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"3.866895283s","start":"2026-02-17T18:38:43.549578Z","end":"2026-02-17T18:38:47.416473Z","steps":["trace[125842796] 'process raft request' (duration: 3.866213808s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:47.416518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:43.549572Z","time spent":"3.866927645s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:38:47.41662Z","caller":"traceutil/trace.go:171","msg":"trace[1310434856] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"3.866999626s","start":"2026-02-17T18:38:43.549614Z","end":"2026-02-17T18:38:47.416614Z","steps":["trace[1310434856] 'process raft request' (duration: 3.866249599s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:47.416661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:43.54961Z","time spent":"3.867029847s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:38:47.416746Z","caller":"traceutil/trace.go:171","msg":"trace[1293786402] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"3.849369285s","start":"2026-02-17T18:38:43.567369Z","end":"2026-02-17T18:38:47.416739Z","steps":["trace[1293786402] 'process raft request' (duration: 3.848537656s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:47.416785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:43.56736Z","time spent":"3.849405505s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:38:47.416889Z","caller":"traceutil/trace.go:171","msg":"trace[1260844363] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"3.849423496s","start":"2026-02-17T18:38:43.567456Z","end":"2026-02-17T18:38:47.416879Z","steps":["trace[1260844363] 'process raft request' (duration: 3.848480805s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:47.416933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:43.56745Z","time spent":"3.849463726s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:38:47.447555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.70034616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T18:38:47.447623Z","caller":"traceutil/trace.go:171","msg":"trace[1170508312] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:888; }","duration":"2.700466372s","start":"2026-02-17T18:38:44.747143Z","end":"2026-02-17T18:38:47.44761Z","steps":["trace[1170508312] 'agreement among raft nodes before linearized reading' (duration: 2.700315479s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:47.447665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:44.747121Z","time spent":"2.700526534s","remote":"127.0.0.1:33450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-17T18:38:47.447936Z","caller":"traceutil/trace.go:171","msg":"trace[694926143] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"2.494368771s","start":"2026-02-17T18:38:44.953559Z","end":"2026-02-17T18:38:47.447928Z","steps":["trace[694926143] 'process raft request' (duration: 2.493795758s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:47.448015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:44.953512Z","time spent":"2.494452962s","remote":"127.0.0.1:33094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:38:47.448154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061211293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:38:47.448183Z","caller":"traceutil/trace.go:171","msg":"trace[45581285] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:888; }","duration":"1.061269984s","start":"2026-02-17T18:38:46.386907Z","end":"2026-02-17T18:38:47.448177Z","steps":["trace[45581285] 'agreement among raft nodes before linearized reading' (duration: 1.061219313s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:47.448206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:46.386885Z","time spent":"1.061316185s","remote":"127.0.0.1:33236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-17T18:38:47.448306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168731381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:38:47.448327Z","caller":"traceutil/trace.go:171","msg":"trace[2028090437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"1.168779292s","start":"2026-02-17T18:38:46.279543Z","end":"2026-02-17T18:38:47.448322Z","steps":["trace[2028090437] 'agreement among raft nodes before linearized reading' (duration: 1.168743331s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:47.448349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:46.279523Z","time spent":"1.168822482s","remote":"127.0.0.1:33074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T18:38:47.448433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.879944076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:38:47.448458Z","caller":"traceutil/trace.go:171","msg":"trace[1074771279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"1.879988578s","start":"2026-02-17T18:38:45.56846Z","end":"2026-02-17T18:38:47.448449Z","steps":["trace[1074771279] 'agreement among raft nodes before linearized reading' (duration: 1.879960107s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:47.448481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:45.568443Z","time spent":"1.880032848s","remote":"127.0.0.1:33078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T18:38:47.973691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.33742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-17T18:38:47.97505Z","caller":"traceutil/trace.go:171","msg":"trace[343920241] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"284.020019ms","start":"2026-02-17T18:38:47.691016Z","end":"2026-02-17T18:38:47.975036Z","steps":["trace[343920241] 'read index received' (duration: 43.921µs)","trace[343920241] 'applied index is now lower than readState.Index' (duration: 283.974718ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:38:47.975107Z","caller":"traceutil/trace.go:171","msg":"trace[568503737] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"512.73665ms","start":"2026-02-17T18:38:47.462357Z","end":"2026-02-17T18:38:47.975094Z","steps":["trace[568503737] 'process raft request' (duration: 43.931507ms)","trace[568503737] 'compare' (duration: 466.816979ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:38:47.97532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:47.462335Z","time spent":"512.933283ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T18:38:47.975202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.174283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T18:38:47.975506Z","caller":"traceutil/trace.go:171","msg":"trace[1877795275] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:889; }","duration":"284.5266ms","start":"2026-02-17T18:38:47.690971Z","end":"2026-02-17T18:38:47.975498Z","steps":["trace[1877795275] 'agreement among raft nodes before linearized reading' (duration: 284.164252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:48.220959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.146328ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T18:38:50.830805Z","caller":"traceutil/trace.go:171","msg":"trace[324802475] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"247.868071ms","start":"2026-02-17T18:38:50.58292Z","end":"2026-02-17T18:38:50.830788Z","steps":["trace[324802475] 'read index received' (duration: 247.710978ms)","trace[324802475] 'applied index is now lower than readState.Index' (duration: 156.383µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:38:50.830921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.976804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:38:50.830938Z","caller":"traceutil/trace.go:171","msg":"trace[444734010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"248.038995ms","start":"2026-02-17T18:38:50.582894Z","end":"2026-02-17T18:38:50.830933Z","steps":["trace[444734010] 'agreement among raft nodes before linearized reading' (duration: 247.959774ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:38:50.8312Z","caller":"traceutil/trace.go:171","msg":"trace[1559912120] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"727.813986ms","start":"2026-02-17T18:38:50.103378Z","end":"2026-02-17T18:38:50.831192Z","steps":["trace[1559912120] 'process raft request' (duration: 727.304424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:50.83127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:50.103356Z","time spent":"727.857577ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:38:57.799556Z","caller":"traceutil/trace.go:171","msg":"trace[369248192] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"237.034539ms","start":"2026-02-17T18:38:57.562499Z","end":"2026-02-17T18:38:57.799533Z","steps":["trace[369248192] 'process raft request' (duration: 168.446811ms)","trace[369248192] 'compare' (duration: 68.331302ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:38:57.804749Z","caller":"traceutil/trace.go:171","msg":"trace[1143582556] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:953; }","duration":"189.697409ms","start":"2026-02-17T18:38:57.615032Z","end":"2026-02-17T18:38:57.804729Z","steps":["trace[1143582556] 'read index received' (duration: 115.922109ms)","trace[1143582556] 'applied index is now lower than readState.Index' (duration: 73.77468ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:38:57.804951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.903713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T18:38:57.804977Z","caller":"traceutil/trace.go:171","msg":"trace[43783540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:916; }","duration":"189.973355ms","start":"2026-02-17T18:38:57.614997Z","end":"2026-02-17T18:38:57.80497Z","steps":["trace[43783540] 'agreement among raft nodes before linearized reading' (duration: 189.800421ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:38:57.805179Z","caller":"traceutil/trace.go:171","msg":"trace[1869185188] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"232.48236ms","start":"2026-02-17T18:38:57.572687Z","end":"2026-02-17T18:38:57.80517Z","steps":["trace[1869185188] 'process raft request' (duration: 231.972569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:58.326117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.471546ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:38:58.326197Z","caller":"traceutil/trace.go:171","msg":"trace[1753343785] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:916; }","duration":"416.564528ms","start":"2026-02-17T18:38:57.909621Z","end":"2026-02-17T18:38:58.326185Z","steps":["trace[1753343785] 'range keys from in-memory index tree' (duration: 416.459316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:58.326763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.768039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T18:38:58.3268Z","caller":"traceutil/trace.go:171","msg":"trace[2089801540] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"508.903997ms","start":"2026-02-17T18:38:57.817888Z","end":"2026-02-17T18:38:58.326792Z","steps":["trace[2089801540] 'process raft request' (duration: 94.051826ms)","trace[2089801540] 'compare' (duration: 414.364811ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:38:58.326837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:57.817868Z","time spent":"508.946039ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:38:58.897756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.727487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:38:58.897805Z","caller":"traceutil/trace.go:171","msg":"trace[551944507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"329.814839ms","start":"2026-02-17T18:38:58.567978Z","end":"2026-02-17T18:38:58.897793Z","steps":["trace[551944507] 'range keys from in-memory index tree' (duration: 329.677135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:38:58.897827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:38:58.567962Z","time spent":"329.86019ms","remote":"127.0.0.1:33074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T18:38:58.898177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.395767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T18:38:58.898194Z","caller":"traceutil/trace.go:171","msg":"trace[1331003311] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:917; }","duration":"218.437338ms","start":"2026-02-17T18:38:58.67975Z","end":"2026-02-17T18:38:58.898188Z","steps":["trace[1331003311] 'count revisions from in-memory index tree' (duration: 218.353267ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:38:59.727398Z","caller":"traceutil/trace.go:171","msg":"trace[255357647] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"156.958023ms","start":"2026-02-17T18:38:59.570419Z","end":"2026-02-17T18:38:59.727377Z","steps":["trace[255357647] 'process raft request' (duration: 81.635489ms)","trace[255357647] 'compare' (duration: 75.1737ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:38:59.910781Z","caller":"traceutil/trace.go:171","msg":"trace[644645375] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"102.583181ms","start":"2026-02-17T18:38:59.80818Z","end":"2026-02-17T18:38:59.910764Z","steps":["trace[644645375] 'process raft request' (duration: 94.288173ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:39:04.285717Z","caller":"traceutil/trace.go:171","msg":"trace[1154147684] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"344.207219ms","start":"2026-02-17T18:39:03.941473Z","end":"2026-02-17T18:39:04.28568Z","steps":["trace[1154147684] 'read index received' (duration: 344.038465ms)","trace[1154147684] 'applied index is now lower than readState.Index' (duration: 168.044µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:39:04.285964Z","caller":"traceutil/trace.go:171","msg":"trace[1588768826] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"361.145385ms","start":"2026-02-17T18:39:03.924808Z","end":"2026-02-17T18:39:04.285954Z","steps":["trace[1588768826] 'process raft request' (duration: 360.767867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:04.286051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:03.924791Z","time spent":"361.193895ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:39:04.286233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.759082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T18:39:04.286257Z","caller":"traceutil/trace.go:171","msg":"trace[1769695414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"344.805924ms","start":"2026-02-17T18:39:03.941444Z","end":"2026-02-17T18:39:04.28625Z","steps":["trace[1769695414] 'agreement among raft nodes before linearized reading' (duration: 344.724612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:04.286272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:03.941428Z","time spent":"344.840045ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T18:39:07.695575Z","caller":"traceutil/trace.go:171","msg":"trace[1698814482] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"120.948568ms","start":"2026-02-17T18:39:07.574452Z","end":"2026-02-17T18:39:07.695401Z","steps":["trace[1698814482] 'process raft request' (duration: 120.808604ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:39:07.900851Z","caller":"traceutil/trace.go:171","msg":"trace[452919783] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"147.469989ms","start":"2026-02-17T18:39:07.753351Z","end":"2026-02-17T18:39:07.900821Z","steps":["trace[452919783] 'read index received' (duration: 50.929808ms)","trace[452919783] 'applied index is now lower than readState.Index' (duration: 96.538941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:39:07.901084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.720944ms","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-17T18:39:07.90112Z","caller":"traceutil/trace.go:171","msg":"trace[1008872707] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"147.817606ms","start":"2026-02-17T18:39:07.75329Z","end":"2026-02-17T18:39:07.901108Z","steps":["trace[1008872707] 'agreement among raft nodes before linearized reading' (duration: 147.631972ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:39:07.901229Z","caller":"traceutil/trace.go:171","msg":"trace[1848101992] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"203.909085ms","start":"2026-02-17T18:39:07.697241Z","end":"2026-02-17T18:39:07.90115Z","steps":["trace[1848101992] 'process raft request' (duration: 107.089228ms)","trace[1848101992] 'compare' (duration: 96.286855ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:39:08.970412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.857748ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T18:39:08.97051Z","caller":"traceutil/trace.go:171","msg":"trace[928624102] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"672.063515ms","start":"2026-02-17T18:39:08.298429Z","end":"2026-02-17T18:39:08.970493Z","steps":["trace[928624102] 'read index received' (duration: 60.036203ms)","trace[928624102] 'applied index is now lower than readState.Index' (duration: 612.025762ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:39:08.972241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.790802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-17T18:39:08.972281Z","caller":"traceutil/trace.go:171","msg":"trace[1240214041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"673.872873ms","start":"2026-02-17T18:39:08.298396Z","end":"2026-02-17T18:39:08.972269Z","steps":["trace[1240214041] 'agreement among raft nodes before linearized reading' (duration: 673.672149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:08.972353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:08.298381Z","time spent":"673.937685ms","remote":"127.0.0.1:33282","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-17T18:39:08.972625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.985454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T18:39:08.972654Z","caller":"traceutil/trace.go:171","msg":"trace[785457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:963; }","duration":"653.032694ms","start":"2026-02-17T18:39:08.319613Z","end":"2026-02-17T18:39:08.972645Z","steps":["trace[785457] 'agreement among raft nodes before linearized reading' (duration: 652.941362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:08.97268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:08.319604Z","time spent":"653.069676ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T18:39:08.97293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.033034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T18:39:08.972982Z","caller":"traceutil/trace.go:171","msg":"trace[964607364] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:963; }","duration":"388.102296ms","start":"2026-02-17T18:39:08.58485Z","end":"2026-02-17T18:39:08.972952Z","steps":["trace[964607364] 'agreement among raft nodes before linearized reading' (duration: 388.039274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:08.973012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:08.584833Z","time spent":"388.169557ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-17T18:39:08.973149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.72767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:39:08.973174Z","caller":"traceutil/trace.go:171","msg":"trace[771821307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"408.77472ms","start":"2026-02-17T18:39:08.564392Z","end":"2026-02-17T18:39:08.973166Z","steps":["trace[771821307] 'agreement among raft nodes before linearized reading' (duration: 408.733469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:08.973199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:08.564376Z","time spent":"408.818362ms","remote":"127.0.0.1:33074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T18:39:08.973364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.959309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T18:39:08.973389Z","caller":"traceutil/trace.go:171","msg":"trace[554503120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:963; }","duration":"592.00964ms","start":"2026-02-17T18:39:08.381371Z","end":"2026-02-17T18:39:08.973381Z","steps":["trace[554503120] 'agreement among raft nodes before linearized reading' (duration: 591.935918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:08.973412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:08.381354Z","time spent":"592.052691ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T18:39:09.344027Z","caller":"traceutil/trace.go:171","msg":"trace[1661164512] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1006; }","duration":"290.509132ms","start":"2026-02-17T18:39:09.053498Z","end":"2026-02-17T18:39:09.344007Z","steps":["trace[1661164512] 'read index received' (duration: 290.121253ms)","trace[1661164512] 'applied index is now lower than readState.Index' (duration: 386.869µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:39:09.344178Z","caller":"traceutil/trace.go:171","msg":"trace[1452980544] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"363.857273ms","start":"2026-02-17T18:39:08.980298Z","end":"2026-02-17T18:39:09.344156Z","steps":["trace[1452980544] 'process raft request' (duration: 363.357552ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:39:09.344229Z","caller":"traceutil/trace.go:171","msg":"trace[409106686] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"363.882193ms","start":"2026-02-17T18:39:08.980341Z","end":"2026-02-17T18:39:09.344223Z","steps":["trace[409106686] 'process raft request' (duration: 363.626618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:09.345081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:08.980337Z","time spent":"364.68539ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:39:09.344303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.944051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:39:09.345202Z","caller":"traceutil/trace.go:171","msg":"trace[1928777909] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:966; }","duration":"317.894681ms","start":"2026-02-17T18:39:09.027301Z","end":"2026-02-17T18:39:09.345196Z","steps":["trace[1928777909] 'agreement among raft nodes before linearized reading' (duration: 316.954831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:09.345222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:09.027281Z","time spent":"317.936452ms","remote":"127.0.0.1:33474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-17T18:39:09.349099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:08.980293Z","time spent":"364.65563ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:39:09.729623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.210149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:39:09.729673Z","caller":"traceutil/trace.go:171","msg":"trace[1657566918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"164.268341ms","start":"2026-02-17T18:39:09.565393Z","end":"2026-02-17T18:39:09.729661Z","steps":["trace[1657566918] 'range keys from in-memory index tree' (duration: 164.121247ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:39:10.188664Z","caller":"traceutil/trace.go:171","msg":"trace[650509782] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"205.677464ms","start":"2026-02-17T18:39:09.982963Z","end":"2026-02-17T18:39:10.18864Z","steps":["trace[650509782] 'process raft request' (duration: 205.501789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:11.536323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.257398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T18:39:11.536637Z","caller":"traceutil/trace.go:171","msg":"trace[1821706404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:968; }","duration":"169.577226ms","start":"2026-02-17T18:39:11.36701Z","end":"2026-02-17T18:39:11.536587Z","steps":["trace[1821706404] 'range keys from in-memory index tree' (duration: 169.109475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:11.537362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.896931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-17T18:39:11.537498Z","caller":"traceutil/trace.go:171","msg":"trace[762786086] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:968; }","duration":"478.066254ms","start":"2026-02-17T18:39:11.059419Z","end":"2026-02-17T18:39:11.537485Z","steps":["trace[762786086] 'range keys from in-memory index tree' (duration: 477.785868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:11.537751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:11.059396Z","time spent":"478.33991ms","remote":"127.0.0.1:33282","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-17T18:39:11.538227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.614586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T18:39:11.538508Z","caller":"traceutil/trace.go:171","msg":"trace[641829291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:968; }","duration":"183.919443ms","start":"2026-02-17T18:39:11.354575Z","end":"2026-02-17T18:39:11.538494Z","steps":["trace[641829291] 'range keys from in-memory index tree' (duration: 183.427083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:11.539846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.856215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:39:11.540082Z","caller":"traceutil/trace.go:171","msg":"trace[1148776976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"978.067279ms","start":"2026-02-17T18:39:10.561955Z","end":"2026-02-17T18:39:11.540022Z","steps":["trace[1148776976] 'range keys from in-memory index tree' (duration: 977.792644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:11.540263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:10.56194Z","time spent":"978.272914ms","remote":"127.0.0.1:33078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T18:39:20.481153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.180479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-17T18:39:20.481271Z","caller":"traceutil/trace.go:171","msg":"trace[1612548619] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"671.366ms","start":"2026-02-17T18:39:19.809879Z","end":"2026-02-17T18:39:20.481245Z","steps":["trace[1612548619] 'process raft request' (duration: 69.007318ms)","trace[1612548619] 'compare' (duration: 596.6691ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:39:20.481323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:19.809867Z","time spent":"671.436281ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T18:39:20.880194Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.001353162s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T18:39:20.880536Z","caller":"traceutil/trace.go:171","msg":"trace[1613635580] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1063; }","duration":"1.022530579s","start":"2026-02-17T18:39:19.857981Z","end":"2026-02-17T18:39:20.880511Z","steps":["trace[1613635580] 'read index received' (duration: 20.911802ms)","trace[1613635580] 'applied index is now lower than readState.Index' (duration: 1.001618056s)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:39:20.880614Z","caller":"traceutil/trace.go:171","msg":"trace[1683037719] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.030822077s","start":"2026-02-17T18:39:19.849784Z","end":"2026-02-17T18:39:20.880606Z","steps":["trace[1683037719] 'process raft request' (duration: 1.030629022s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:20.880688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:19.849759Z","time spent":"1.030870188s","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":784,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T18:39:20.880923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022932897s","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-17T18:39:20.880942Z","caller":"traceutil/trace.go:171","msg":"trace[1729096600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1021; }","duration":"1.022976958s","start":"2026-02-17T18:39:19.857959Z","end":"2026-02-17T18:39:20.880936Z","steps":["trace[1729096600] 'agreement among raft nodes before linearized reading' (duration: 1.022845775s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:20.880958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:19.857951Z","time spent":"1.023004228s","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-17T18:39:20.916659Z","caller":"traceutil/trace.go:171","msg":"trace[501460732] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"427.547314ms","start":"2026-02-17T18:39:20.489084Z","end":"2026-02-17T18:39:20.916631Z","steps":["trace[501460732] 'process raft request' (duration: 427.437002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:20.916701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.662042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T18:39:20.916748Z","caller":"traceutil/trace.go:171","msg":"trace[882096120] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1022; }","duration":"811.753214ms","start":"2026-02-17T18:39:20.104983Z","end":"2026-02-17T18:39:20.916736Z","steps":["trace[882096120] 'agreement among raft nodes before linearized reading' (duration: 811.642992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:20.916775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:20.104966Z","time spent":"811.802395ms","remote":"127.0.0.1:33156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-17T18:39:20.916802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:20.489059Z","time spent":"427.670706ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T18:39:20.916974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.115511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:39:20.916992Z","caller":"traceutil/trace.go:171","msg":"trace[997232415] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1022; }","duration":"148.169633ms","start":"2026-02-17T18:39:20.768817Z","end":"2026-02-17T18:39:20.916987Z","steps":["trace[997232415] 'agreement among raft nodes before linearized reading' (duration: 148.137012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:20.917082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.045393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:39:20.917094Z","caller":"traceutil/trace.go:171","msg":"trace[285072838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"356.084913ms","start":"2026-02-17T18:39:20.561005Z","end":"2026-02-17T18:39:20.91709Z","steps":["trace[285072838] 'agreement among raft nodes before linearized reading' (duration: 356.060962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:20.917106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:20.560988Z","time spent":"356.115504ms","remote":"127.0.0.1:33074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T18:39:20.917222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.520524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T18:39:20.917252Z","caller":"traceutil/trace.go:171","msg":"trace[2116772658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1022; }","duration":"673.593756ms","start":"2026-02-17T18:39:20.24365Z","end":"2026-02-17T18:39:20.917244Z","steps":["trace[2116772658] 'agreement among raft nodes before linearized reading' (duration: 673.467603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:20.917274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:20.24363Z","time spent":"673.639557ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T18:39:20.917414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.430676ms","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:527"} {"level":"info","ts":"2026-02-17T18:39:20.917433Z","caller":"traceutil/trace.go:171","msg":"trace[1665936774] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1022; }","duration":"430.473566ms","start":"2026-02-17T18:39:20.486954Z","end":"2026-02-17T18:39:20.917427Z","steps":["trace[1665936774] 'agreement among raft nodes before linearized reading' (duration: 430.392874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:20.917447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:39:20.486933Z","time spent":"430.510556ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":551,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-17T18:39:53.221693Z","caller":"traceutil/trace.go:171","msg":"trace[1041356762] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"100.258614ms","start":"2026-02-17T18:39:53.121417Z","end":"2026-02-17T18:39:53.221675Z","steps":["trace[1041356762] 'process raft request' (duration: 100.017209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:39:59.868458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.119461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T18:39:59.868745Z","caller":"traceutil/trace.go:171","msg":"trace[749454681] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"256.641543ms","start":"2026-02-17T18:39:59.612091Z","end":"2026-02-17T18:39:59.868733Z","steps":["trace[749454681] 'process raft request' (duration: 110.181704ms)","trace[749454681] 'compare' (duration: 146.019949ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:42:34.970083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.810059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T18:42:34.970171Z","caller":"traceutil/trace.go:171","msg":"trace[523999927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1854; }","duration":"107.950392ms","start":"2026-02-17T18:42:34.862202Z","end":"2026-02-17T18:42:34.970152Z","steps":["trace[523999927] 'range keys from in-memory index tree' (duration: 107.622474ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:42:35.078025Z","caller":"traceutil/trace.go:171","msg":"trace[1723446357] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"103.165025ms","start":"2026-02-17T18:42:34.974835Z","end":"2026-02-17T18:42:35.078Z","steps":["trace[1723446357] 'process raft request' (duration: 103.023371ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:42:35.339105Z","caller":"traceutil/trace.go:171","msg":"trace[1994604271] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"166.665362ms","start":"2026-02-17T18:42:35.172413Z","end":"2026-02-17T18:42:35.339078Z","steps":["trace[1994604271] 'process raft request' (duration: 94.667497ms)","trace[1994604271] 'compare' (duration: 71.882683ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:42:38.128403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.344093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:42:38.128463Z","caller":"traceutil/trace.go:171","msg":"trace[803033381] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1867; }","duration":"374.451394ms","start":"2026-02-17T18:42:37.753994Z","end":"2026-02-17T18:42:38.128445Z","steps":["trace[803033381] 'count revisions from in-memory index tree' (duration: 374.247121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:42:38.129345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:42:37.753966Z","time spent":"375.366296ms","remote":"127.0.0.1:33420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-17T18:42:38.129545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.424799ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:42:38.129596Z","caller":"traceutil/trace.go:171","msg":"trace[1791488031] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1867; }","duration":"218.47229ms","start":"2026-02-17T18:42:37.911113Z","end":"2026-02-17T18:42:38.129585Z","steps":["trace[1791488031] 'range keys from in-memory index tree' (duration: 218.417339ms)"],"step_count":1}