{"level":"warn","ts":"2026-02-12T00:54:37.245332Z","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-12T00:54:37.245447Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.99:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.99:2380","--initial-cluster=instance=https://199.204.45.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.99:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-12T00:54:37.245547Z","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-12T00:54:37.245556Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-12T00:54:37.245589Z","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-12T00:54:37.246287Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"]} {"level":"info","ts":"2026-02-12T00:54:37.246377Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.99:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-12T00:54:37.253279Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.206574ms"} {"level":"info","ts":"2026-02-12T00:54:37.277237Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"21032583b6cd9182","cluster-id":"a96cbe7391f279b7"} {"level":"info","ts":"2026-02-12T00:54:37.277334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T00:54:37.277361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 0"} {"level":"info","ts":"2026-02-12T00:54:37.277392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21032583b6cd9182 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T00:54:37.277401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 1"} {"level":"info","ts":"2026-02-12T00:54:37.277441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"warn","ts":"2026-02-12T00:54:37.283671Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T00:54:37.284776Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T00:54:37.285605Z","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-12T00:54:37.29679Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"21032583b6cd9182","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T00:54:37.297584Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"21032583b6cd9182","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T00:54:37.297754Z","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-12T00:54:37.297788Z","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-12T00:54:37.297803Z","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-12T00:54:37.303965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"info","ts":"2026-02-12T00:54:37.305609Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","added-peer-id":"21032583b6cd9182","added-peer-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-12T00:54:37.333316Z","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-12T00:54:37.333541Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"21032583b6cd9182","initial-advertise-peer-urls":["https://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T00:54:37.333572Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T00:54:37.333634Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-12T00:54:37.339842Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-12T00:54:37.787028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T00:54:37.787069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T00:54:37.787096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgPreVoteResp from 21032583b6cd9182 at term 1"} {"level":"info","ts":"2026-02-12T00:54:37.787124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became candidate at term 2"} {"level":"info","ts":"2026-02-12T00:54:37.787133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgVoteResp from 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-12T00:54:37.787141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became leader at term 2"} {"level":"info","ts":"2026-02-12T00:54:37.787152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21032583b6cd9182 elected leader 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-12T00:54:37.78801Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"21032583b6cd9182","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.99:2379]}","request-path":"/0/members/21032583b6cd9182/attributes","cluster-id":"a96cbe7391f279b7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T00:54:37.788186Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T00:54:37.788182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T00:54:37.788638Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T00:54:37.788664Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T00:54:37.790105Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T00:54:37.790567Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T00:54:37.799599Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.99:2379"} {"level":"info","ts":"2026-02-12T00:54:37.800411Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T00:54:37.800825Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T00:54:37.800906Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-12T00:54:38.001798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.725854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-12T00:54:38.001848Z","caller":"traceutil/trace.go:171","msg":"trace[243956909] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"100.801184ms","start":"2026-02-12T00:54:37.901036Z","end":"2026-02-12T00:54:38.001837Z","steps":["trace[243956909] 'count revisions from in-memory index tree' (duration: 100.693963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:39.744245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.03386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-12T00:54:39.744314Z","caller":"traceutil/trace.go:171","msg":"trace[1186654815] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:4; }","duration":"125.120571ms","start":"2026-02-12T00:54:39.619178Z","end":"2026-02-12T00:54:39.744299Z","steps":["trace[1186654815] 'agreement among raft nodes before linearized reading' (duration: 125.01942ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:39.783362Z","caller":"traceutil/trace.go:171","msg":"trace[1498082420] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"120.650075ms","start":"2026-02-12T00:54:39.662693Z","end":"2026-02-12T00:54:39.783343Z","steps":["trace[1498082420] 'process raft request' (duration: 120.276161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:44.367426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114747060608885,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T00:54:45.583378Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114747060608885,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T00:54:45.922819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.105798792s","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-12T00:54:45.922863Z","caller":"traceutil/trace.go:171","msg":"trace[1274691686] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:266; }","duration":"2.105884083s","start":"2026-02-12T00:54:43.816969Z","end":"2026-02-12T00:54:45.922853Z","steps":["trace[1274691686] 'range keys from in-memory index tree' (duration: 2.105743542s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:45.922891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:43.816955Z","time spent":"2.105930674s","remote":"127.0.0.1:34400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-12T00:54:45.926201Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.06039514s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T00:54:45.926394Z","caller":"traceutil/trace.go:171","msg":"trace[529381778] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"2.060651342s","start":"2026-02-12T00:54:43.865733Z","end":"2026-02-12T00:54:45.926384Z","steps":["trace[529381778] 'process raft request' (duration: 2.060609192s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:45.926641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:43.865715Z","time spent":"2.060698533s","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T00:54:46.13845Z","caller":"traceutil/trace.go:171","msg":"trace[1731074220] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:277; }","duration":"2.271434849s","start":"2026-02-12T00:54:43.866992Z","end":"2026-02-12T00:54:46.138427Z","steps":["trace[1731074220] 'read index received' (duration: 2.059480591s)","trace[1731074220] 'applied index is now lower than readState.Index' (duration: 211.951378ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:54:46.138602Z","caller":"traceutil/trace.go:171","msg":"trace[355604827] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"2.269935654s","start":"2026-02-12T00:54:43.868658Z","end":"2026-02-12T00:54:46.138594Z","steps":["trace[355604827] 'process raft request' (duration: 2.269709402s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:46.138684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:43.868651Z","time spent":"2.269982165s","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T00:54:46.139135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.272130395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-12T00:54:46.139176Z","caller":"traceutil/trace.go:171","msg":"trace[1793984171] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:266; }","duration":"2.272191376s","start":"2026-02-12T00:54:43.866974Z","end":"2026-02-12T00:54:46.139166Z","steps":["trace[1793984171] 'agreement among raft nodes before linearized reading' (duration: 2.272113975s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:46.139211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:43.866968Z","time spent":"2.272235796s","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3467,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-02-12T00:54:46.182334Z","caller":"traceutil/trace.go:171","msg":"trace[349221321] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"255.265538ms","start":"2026-02-12T00:54:45.927049Z","end":"2026-02-12T00:54:46.182315Z","steps":["trace[349221321] 'process raft request' (duration: 255.165827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:46.183083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.379889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-12T00:54:46.183131Z","caller":"traceutil/trace.go:171","msg":"trace[1222812270] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:267; }","duration":"255.44671ms","start":"2026-02-12T00:54:45.927672Z","end":"2026-02-12T00:54:46.183119Z","steps":["trace[1222812270] 'agreement among raft nodes before linearized reading' (duration: 255.345869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:46.184607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.179703896s","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-12T00:54:46.184629Z","caller":"traceutil/trace.go:171","msg":"trace[1492070596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:267; }","duration":"2.179755776s","start":"2026-02-12T00:54:44.004867Z","end":"2026-02-12T00:54:46.184623Z","steps":["trace[1492070596] 'agreement among raft nodes before linearized reading' (duration: 2.179710546s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:46.184645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:44.004851Z","time spent":"2.179789666s","remote":"127.0.0.1:34560","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-12T00:54:46.184655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.029681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T00:54:46.184726Z","caller":"traceutil/trace.go:171","msg":"trace[718801281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:267; }","duration":"602.135822ms","start":"2026-02-12T00:54:45.582579Z","end":"2026-02-12T00:54:46.184715Z","steps":["trace[718801281] 'agreement among raft nodes before linearized reading' (duration: 601.95904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:46.184736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.008162831s","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-12T00:54:46.184754Z","caller":"traceutil/trace.go:171","msg":"trace[1187855932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:267; }","duration":"2.008237052s","start":"2026-02-12T00:54:44.176511Z","end":"2026-02-12T00:54:46.184748Z","steps":["trace[1187855932] 'agreement among raft nodes before linearized reading' (duration: 2.008206502s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:46.184767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:44.176489Z","time spent":"2.008274932s","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T00:54:46.184759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:45.582565Z","time spent":"602.185122ms","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T00:54:46.184886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.264688823s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:54:46.184901Z","caller":"traceutil/trace.go:171","msg":"trace[461479532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:267; }","duration":"2.264733733s","start":"2026-02-12T00:54:43.920163Z","end":"2026-02-12T00:54:46.184897Z","steps":["trace[461479532] 'agreement among raft nodes before linearized reading' (duration: 2.264711662s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:46.184914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:43.920146Z","time spent":"2.264764973s","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-12T00:54:46.390356Z","caller":"traceutil/trace.go:171","msg":"trace[783660313] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:279; }","duration":"207.370338ms","start":"2026-02-12T00:54:46.182971Z","end":"2026-02-12T00:54:46.390341Z","steps":["trace[783660313] 'read index received' (duration: 170.002244ms)","trace[783660313] 'applied index is now lower than readState.Index' (duration: 37.367554ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:54:46.390535Z","caller":"traceutil/trace.go:171","msg":"trace[1102812832] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"233.538746ms","start":"2026-02-12T00:54:46.15699Z","end":"2026-02-12T00:54:46.390529Z","steps":["trace[1102812832] 'process raft request' (duration: 196.01719ms)","trace[1102812832] 'compare' (duration: 37.255914ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:46.390645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.138154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5870"} {"level":"info","ts":"2026-02-12T00:54:46.390661Z","caller":"traceutil/trace.go:171","msg":"trace[2001725540] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:268; }","duration":"250.163924ms","start":"2026-02-12T00:54:46.140493Z","end":"2026-02-12T00:54:46.390657Z","steps":["trace[2001725540] 'agreement among raft nodes before linearized reading' (duration: 250.121464ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:46.424095Z","caller":"traceutil/trace.go:171","msg":"trace[1380909948] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"231.718489ms","start":"2026-02-12T00:54:46.192359Z","end":"2026-02-12T00:54:46.424077Z","steps":["trace[1380909948] 'process raft request' (duration: 231.624948ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:46.42427Z","caller":"traceutil/trace.go:171","msg":"trace[1764209155] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"231.90665ms","start":"2026-02-12T00:54:46.192359Z","end":"2026-02-12T00:54:46.424266Z","steps":["trace[1764209155] 'process raft request' (duration: 231.700969ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:46.424308Z","caller":"traceutil/trace.go:171","msg":"trace[1997862852] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"230.125754ms","start":"2026-02-12T00:54:46.194179Z","end":"2026-02-12T00:54:46.424304Z","steps":["trace[1997862852] 'process raft request' (duration: 230.037573ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:46.424369Z","caller":"traceutil/trace.go:171","msg":"trace[788451972] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"228.919132ms","start":"2026-02-12T00:54:46.195447Z","end":"2026-02-12T00:54:46.424366Z","steps":["trace[788451972] 'process raft request' (duration: 228.787921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:46.424428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.086573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:54:46.424443Z","caller":"traceutil/trace.go:171","msg":"trace[1345973387] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:272; }","duration":"232.136553ms","start":"2026-02-12T00:54:46.192302Z","end":"2026-02-12T00:54:46.424438Z","steps":["trace[1345973387] 'agreement among raft nodes before linearized reading' (duration: 232.093053ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:46.694398Z","caller":"traceutil/trace.go:171","msg":"trace[1116588718] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"263.217488ms","start":"2026-02-12T00:54:46.431158Z","end":"2026-02-12T00:54:46.694375Z","steps":["trace[1116588718] 'read index received' (duration: 221.125578ms)","trace[1116588718] 'applied index is now lower than readState.Index' (duration: 42.09093ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:54:46.694411Z","caller":"traceutil/trace.go:171","msg":"trace[786876724] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"263.246678ms","start":"2026-02-12T00:54:46.431138Z","end":"2026-02-12T00:54:46.694385Z","steps":["trace[786876724] 'process raft request' (duration: 221.185939ms)","trace[786876724] 'compare' (duration: 41.915738ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:46.694536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.356259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-12T00:54:46.694566Z","caller":"traceutil/trace.go:171","msg":"trace[2101852228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:273; }","duration":"263.40978ms","start":"2026-02-12T00:54:46.431149Z","end":"2026-02-12T00:54:46.694559Z","steps":["trace[2101852228] 'agreement among raft nodes before linearized reading' (duration: 263.300349ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:46.772973Z","caller":"traceutil/trace.go:171","msg":"trace[606069300] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"333.695127ms","start":"2026-02-12T00:54:46.43926Z","end":"2026-02-12T00:54:46.772956Z","steps":["trace[606069300] 'process raft request' (duration: 333.632536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:46.773283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:46.439252Z","time spent":"333.777487ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5245,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T00:54:46.773512Z","caller":"traceutil/trace.go:171","msg":"trace[288570181] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"337.396392ms","start":"2026-02-12T00:54:46.436105Z","end":"2026-02-12T00:54:46.773501Z","steps":["trace[288570181] 'process raft request' (duration: 336.693215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:46.773566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:46.436085Z","time spent":"337.458222ms","remote":"127.0.0.1:34506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":184,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T00:54:47.02425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.997426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T00:54:47.024316Z","caller":"traceutil/trace.go:171","msg":"trace[1131702663] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:287; }","duration":"243.604751ms","start":"2026-02-12T00:54:46.780702Z","end":"2026-02-12T00:54:47.024307Z","steps":["trace[1131702663] 'read index received' (duration: 41.362493ms)","trace[1131702663] 'applied index is now lower than readState.Index' (duration: 202.241538ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:54:47.02449Z","caller":"traceutil/trace.go:171","msg":"trace[1892934500] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"325.463707ms","start":"2026-02-12T00:54:46.69902Z","end":"2026-02-12T00:54:47.024484Z","steps":["trace[1892934500] 'process raft request' (duration: 123.100118ms)","trace[1892934500] 'compare' (duration: 201.914605ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:47.024519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:46.699001Z","time spent":"325.503107ms","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T00:54:47.024615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.911653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5870"} {"level":"info","ts":"2026-02-12T00:54:47.024629Z","caller":"traceutil/trace.go:171","msg":"trace[170482968] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:276; }","duration":"243.948323ms","start":"2026-02-12T00:54:46.780675Z","end":"2026-02-12T00:54:47.024623Z","steps":["trace[170482968] 'agreement among raft nodes before linearized reading' (duration: 243.920723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:47.024701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.878912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:54:47.024713Z","caller":"traceutil/trace.go:171","msg":"trace[1636989963] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:0; response_revision:276; }","duration":"243.889933ms","start":"2026-02-12T00:54:46.780819Z","end":"2026-02-12T00:54:47.024709Z","steps":["trace[1636989963] 'agreement among raft nodes before linearized reading' (duration: 243.872013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:47.340876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.900778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T00:54:47.34106Z","caller":"traceutil/trace.go:171","msg":"trace[38508193] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"311.702485ms","start":"2026-02-12T00:54:47.029339Z","end":"2026-02-12T00:54:47.341042Z","steps":["trace[38508193] 'process raft request' (duration: 138.544635ms)","trace[38508193] 'compare' (duration: 172.416603ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:47.341131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:47.029326Z","time spent":"311.771046ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T00:54:47.341003Z","caller":"traceutil/trace.go:171","msg":"trace[970694803] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:288; }","duration":"311.314632ms","start":"2026-02-12T00:54:47.02967Z","end":"2026-02-12T00:54:47.340985Z","steps":["trace[970694803] 'read index received' (duration: 138.161371ms)","trace[970694803] 'applied index is now lower than readState.Index' (duration: 173.151611ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:47.341553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.875347ms","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-12T00:54:47.341588Z","caller":"traceutil/trace.go:171","msg":"trace[665027287] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:277; }","duration":"311.932967ms","start":"2026-02-12T00:54:47.029643Z","end":"2026-02-12T00:54:47.341576Z","steps":["trace[665027287] 'agreement among raft nodes before linearized reading' (duration: 311.864657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:47.341619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:47.029628Z","time spent":"311.983018ms","remote":"127.0.0.1:34400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-12T00:54:47.341789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.56049ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:54:47.341892Z","caller":"traceutil/trace.go:171","msg":"trace[1517517781] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:277; }","duration":"232.663341ms","start":"2026-02-12T00:54:47.109219Z","end":"2026-02-12T00:54:47.341882Z","steps":["trace[1517517781] 'agreement among raft nodes before linearized reading' (duration: 232.5473ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:47.549902Z","caller":"traceutil/trace.go:171","msg":"trace[1399466692] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"138.759457ms","start":"2026-02-12T00:54:47.411103Z","end":"2026-02-12T00:54:47.549862Z","steps":["trace[1399466692] 'read index received' (duration: 58.702983ms)","trace[1399466692] 'applied index is now lower than readState.Index' (duration: 80.054054ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:47.550373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.225451ms","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-12T00:54:47.550429Z","caller":"traceutil/trace.go:171","msg":"trace[60517973] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:279; }","duration":"139.344282ms","start":"2026-02-12T00:54:47.411073Z","end":"2026-02-12T00:54:47.550417Z","steps":["trace[60517973] 'agreement among raft nodes before linearized reading' (duration: 138.950628ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:47.550888Z","caller":"traceutil/trace.go:171","msg":"trace[958329169] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"147.798782ms","start":"2026-02-12T00:54:47.403049Z","end":"2026-02-12T00:54:47.550848Z","steps":["trace[958329169] 'process raft request' (duration: 66.81938ms)","trace[958329169] 'compare' (duration: 79.766191ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:54:47.865666Z","caller":"traceutil/trace.go:171","msg":"trace[2103280839] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:292; }","duration":"232.083266ms","start":"2026-02-12T00:54:47.633554Z","end":"2026-02-12T00:54:47.865638Z","steps":["trace[2103280839] 'read index received' (duration: 139.09706ms)","trace[2103280839] 'applied index is now lower than readState.Index' (duration: 92.985046ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:47.865803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.219947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-12T00:54:47.865848Z","caller":"traceutil/trace.go:171","msg":"trace[849595702] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:281; }","duration":"232.319428ms","start":"2026-02-12T00:54:47.633516Z","end":"2026-02-12T00:54:47.865836Z","steps":["trace[849595702] 'agreement among raft nodes before linearized reading' (duration: 232.218777ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:47.866306Z","caller":"traceutil/trace.go:171","msg":"trace[1528924762] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"304.985962ms","start":"2026-02-12T00:54:47.561305Z","end":"2026-02-12T00:54:47.866291Z","steps":["trace[1528924762] 'process raft request' (duration: 211.407731ms)","trace[1528924762] 'compare' (duration: 92.790193ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:47.866464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:47.561292Z","time spent":"305.085843ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4241,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T00:54:48.301902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.510428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T00:54:48.301971Z","caller":"traceutil/trace.go:171","msg":"trace[648888033] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:293; }","duration":"416.842353ms","start":"2026-02-12T00:54:47.885117Z","end":"2026-02-12T00:54:48.301959Z","steps":["trace[648888033] 'read index received' (duration: 102.204163ms)","trace[648888033] 'applied index is now lower than readState.Index' (duration: 314.63745ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:48.302025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.906583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:54:48.302043Z","caller":"traceutil/trace.go:171","msg":"trace[920575496] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:282; }","duration":"416.947153ms","start":"2026-02-12T00:54:47.885088Z","end":"2026-02-12T00:54:48.302036Z","steps":["trace[920575496] 'agreement among raft nodes before linearized reading' (duration: 416.900963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:48.302063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:47.885073Z","time spent":"416.984934ms","remote":"127.0.0.1:34506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" "} {"level":"info","ts":"2026-02-12T00:54:48.302243Z","caller":"traceutil/trace.go:171","msg":"trace[1162600800] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"419.191464ms","start":"2026-02-12T00:54:47.883041Z","end":"2026-02-12T00:54:48.302232Z","steps":["trace[1162600800] 'process raft request' (duration: 104.282802ms)","trace[1162600800] 'compare' (duration: 314.435258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:48.302283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:47.882955Z","time spent":"419.307066ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5073,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T00:54:48.642534Z","caller":"traceutil/trace.go:171","msg":"trace[361733396] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:295; }","duration":"213.0002ms","start":"2026-02-12T00:54:48.429512Z","end":"2026-02-12T00:54:48.642512Z","steps":["trace[361733396] 'read index received' (duration: 180.742799ms)","trace[361733396] 'applied index is now lower than readState.Index' (duration: 32.256191ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:48.643256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.18905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T00:54:48.643331Z","caller":"traceutil/trace.go:171","msg":"trace[62420236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:284; }","duration":"202.310231ms","start":"2026-02-12T00:54:48.441003Z","end":"2026-02-12T00:54:48.643313Z","steps":["trace[62420236] 'agreement among raft nodes before linearized reading' (duration: 202.127899ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:48.643466Z","caller":"traceutil/trace.go:171","msg":"trace[1959018110] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"255.646819ms","start":"2026-02-12T00:54:48.38781Z","end":"2026-02-12T00:54:48.643457Z","steps":["trace[1959018110] 'process raft request' (duration: 222.50864ms)","trace[1959018110] 'compare' (duration: 31.841587ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:48.643593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.07831ms","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-12T00:54:48.643636Z","caller":"traceutil/trace.go:171","msg":"trace[1681139775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:284; }","duration":"214.151872ms","start":"2026-02-12T00:54:48.429477Z","end":"2026-02-12T00:54:48.643629Z","steps":["trace[1681139775] 'agreement among raft nodes before linearized reading' (duration: 214.091011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:48.644113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.486932ms","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-12T00:54:48.644141Z","caller":"traceutil/trace.go:171","msg":"trace[1171585947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:284; }","duration":"202.533072ms","start":"2026-02-12T00:54:48.441601Z","end":"2026-02-12T00:54:48.644134Z","steps":["trace[1171585947] 'agreement among raft nodes before linearized reading' (duration: 202.133679ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:48.878518Z","caller":"traceutil/trace.go:171","msg":"trace[742230317] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:296; }","duration":"229.770507ms","start":"2026-02-12T00:54:48.648727Z","end":"2026-02-12T00:54:48.878497Z","steps":["trace[742230317] 'read index received' (duration: 179.605028ms)","trace[742230317] 'applied index is now lower than readState.Index' (duration: 50.164499ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:54:48.878674Z","caller":"traceutil/trace.go:171","msg":"trace[1455350294] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"227.927939ms","start":"2026-02-12T00:54:48.650727Z","end":"2026-02-12T00:54:48.878655Z","steps":["trace[1455350294] 'process raft request' (duration: 227.892749ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:48.878723Z","caller":"traceutil/trace.go:171","msg":"trace[1271540629] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"228.723007ms","start":"2026-02-12T00:54:48.649993Z","end":"2026-02-12T00:54:48.878716Z","steps":["trace[1271540629] 'process raft request' (duration: 228.545815ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:48.878898Z","caller":"traceutil/trace.go:171","msg":"trace[1239313965] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"230.740656ms","start":"2026-02-12T00:54:48.648149Z","end":"2026-02-12T00:54:48.87889Z","steps":["trace[1239313965] 'process raft request' (duration: 180.174033ms)","trace[1239313965] 'compare' (duration: 49.976337ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:48.878954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.212611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:54:48.8794Z","caller":"traceutil/trace.go:171","msg":"trace[1755770029] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:0; response_revision:287; }","duration":"230.683915ms","start":"2026-02-12T00:54:48.648705Z","end":"2026-02-12T00:54:48.879389Z","steps":["trace[1755770029] 'agreement among raft nodes before linearized reading' (duration: 230.216111ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:49.023384Z","caller":"traceutil/trace.go:171","msg":"trace[944816163] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"121.882187ms","start":"2026-02-12T00:54:48.901479Z","end":"2026-02-12T00:54:49.023361Z","steps":["trace[944816163] 'process raft request' (duration: 97.598242ms)","trace[944816163] 'compare' (duration: 24.048863ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:54:49.775247Z","caller":"traceutil/trace.go:171","msg":"trace[1399106696] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:317; }","duration":"251.680464ms","start":"2026-02-12T00:54:49.523551Z","end":"2026-02-12T00:54:49.775231Z","steps":["trace[1399106696] 'read index received' (duration: 60.127517ms)","trace[1399106696] 'applied index is now lower than readState.Index' (duration: 191.552297ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:54:49.7755Z","caller":"traceutil/trace.go:171","msg":"trace[1000232876] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"255.214548ms","start":"2026-02-12T00:54:49.520276Z","end":"2026-02-12T00:54:49.77549Z","steps":["trace[1000232876] 'process raft request' (duration: 236.592975ms)","trace[1000232876] 'compare' (duration: 18.112128ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:54:49.775724Z","caller":"traceutil/trace.go:171","msg":"trace[1973385978] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"252.524993ms","start":"2026-02-12T00:54:49.52319Z","end":"2026-02-12T00:54:49.775715Z","steps":["trace[1973385978] 'process raft request' (duration: 252.007068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:49.775955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.390122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-02-12T00:54:49.776016Z","caller":"traceutil/trace.go:171","msg":"trace[1547549000] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:307; }","duration":"252.479063ms","start":"2026-02-12T00:54:49.523528Z","end":"2026-02-12T00:54:49.776007Z","steps":["trace[1547549000] 'agreement among raft nodes before linearized reading' (duration: 252.362292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:49.776313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.406288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-12T00:54:49.777355Z","caller":"traceutil/trace.go:171","msg":"trace[74901609] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:307; }","duration":"244.478618ms","start":"2026-02-12T00:54:49.532865Z","end":"2026-02-12T00:54:49.777344Z","steps":["trace[74901609] 'agreement among raft nodes before linearized reading' (duration: 243.346278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:49.776803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.458911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-12T00:54:49.77755Z","caller":"traceutil/trace.go:171","msg":"trace[857566669] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:307; }","duration":"144.234078ms","start":"2026-02-12T00:54:49.633306Z","end":"2026-02-12T00:54:49.77754Z","steps":["trace[857566669] 'agreement among raft nodes before linearized reading' (duration: 143.460541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:49.776847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.546205ms","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-12T00:54:49.777699Z","caller":"traceutil/trace.go:171","msg":"trace[1821226482] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:307; }","duration":"194.410414ms","start":"2026-02-12T00:54:49.58327Z","end":"2026-02-12T00:54:49.777681Z","steps":["trace[1821226482] 'agreement among raft nodes before linearized reading' (duration: 193.556866ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:49.961366Z","caller":"traceutil/trace.go:171","msg":"trace[2061020475] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"162.574278ms","start":"2026-02-12T00:54:49.798753Z","end":"2026-02-12T00:54:49.961328Z","steps":["trace[2061020475] 'process raft request' (duration: 162.473447ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:49.961619Z","caller":"traceutil/trace.go:171","msg":"trace[1542746713] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"165.302324ms","start":"2026-02-12T00:54:49.796297Z","end":"2026-02-12T00:54:49.9616Z","steps":["trace[1542746713] 'process raft request' (duration: 124.399044ms)","trace[1542746713] 'compare' (duration: 40.323044ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:54:49.961686Z","caller":"traceutil/trace.go:171","msg":"trace[187183817] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"165.301953ms","start":"2026-02-12T00:54:49.796373Z","end":"2026-02-12T00:54:49.961675Z","steps":["trace[187183817] 'process raft request' (duration: 164.780208ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:49.977226Z","caller":"traceutil/trace.go:171","msg":"trace[1912938550] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:322; }","duration":"144.258098ms","start":"2026-02-12T00:54:49.832945Z","end":"2026-02-12T00:54:49.977204Z","steps":["trace[1912938550] 'read index received' (duration: 87.779674ms)","trace[1912938550] 'applied index is now lower than readState.Index' (duration: 56.477794ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:54:49.977532Z","caller":"traceutil/trace.go:171","msg":"trace[570551841] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"156.562802ms","start":"2026-02-12T00:54:49.820956Z","end":"2026-02-12T00:54:49.977518Z","steps":["trace[570551841] 'process raft request' (duration: 156.110358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:49.977761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.773653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-12T00:54:49.977859Z","caller":"traceutil/trace.go:171","msg":"trace[892184127] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:314; }","duration":"144.927265ms","start":"2026-02-12T00:54:49.832913Z","end":"2026-02-12T00:54:49.97784Z","steps":["trace[892184127] 'agreement among raft nodes before linearized reading' (duration: 144.770664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:50.004243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.954974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-12T00:54:50.00444Z","caller":"traceutil/trace.go:171","msg":"trace[683511648] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:315; }","duration":"119.140105ms","start":"2026-02-12T00:54:49.885228Z","end":"2026-02-12T00:54:50.004368Z","steps":["trace[683511648] 'agreement among raft nodes before linearized reading' (duration: 118.917703ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:50.954524Z","caller":"traceutil/trace.go:171","msg":"trace[2077855681] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"113.104552ms","start":"2026-02-12T00:54:50.841403Z","end":"2026-02-12T00:54:50.954508Z","steps":["trace[2077855681] 'process raft request' (duration: 112.994811ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:51.495814Z","caller":"traceutil/trace.go:171","msg":"trace[1169320308] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:357; }","duration":"515.288874ms","start":"2026-02-12T00:54:50.980502Z","end":"2026-02-12T00:54:51.495791Z","steps":["trace[1169320308] 'read index received' (duration: 15.276631ms)","trace[1169320308] 'applied index is now lower than readState.Index' (duration: 500.011163ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:51.496207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.690018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4012"} {"level":"info","ts":"2026-02-12T00:54:51.496241Z","caller":"traceutil/trace.go:171","msg":"trace[953109282] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:347; }","duration":"515.746208ms","start":"2026-02-12T00:54:50.980487Z","end":"2026-02-12T00:54:51.496233Z","steps":["trace[953109282] 'agreement among raft nodes before linearized reading' (duration: 515.679738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:51.49626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:50.980479Z","time spent":"515.776338ms","remote":"127.0.0.1:34480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4035,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-12T00:54:51.495856Z","caller":"traceutil/trace.go:171","msg":"trace[1396827475] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"535.177227ms","start":"2026-02-12T00:54:50.960646Z","end":"2026-02-12T00:54:51.495824Z","steps":["trace[1396827475] 'process raft request' (duration: 534.988416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:51.496414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:50.96064Z","time spent":"535.737683ms","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T00:54:51.496148Z","caller":"traceutil/trace.go:171","msg":"trace[1965217721] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"535.464879ms","start":"2026-02-12T00:54:50.960678Z","end":"2026-02-12T00:54:51.496142Z","steps":["trace[1965217721] 'process raft request' (duration: 535.076546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:51.496513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:50.960674Z","time spent":"535.824113ms","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T00:54:51.496792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.201963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T00:54:51.496813Z","caller":"traceutil/trace.go:171","msg":"trace[1875392431] range","detail":"{range_begin:/registry/masterleases/199.204.45.99; range_end:; response_count:1; response_revision:347; }","duration":"349.263225ms","start":"2026-02-12T00:54:51.147544Z","end":"2026-02-12T00:54:51.496807Z","steps":["trace[1875392431] 'agreement among raft nodes before linearized reading' (duration: 349.213814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:51.496827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:51.147523Z","time spent":"349.301025ms","remote":"127.0.0.1:34306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.99\" "} {"level":"warn","ts":"2026-02-12T00:54:51.4968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.805998ms","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-12T00:54:51.49718Z","caller":"traceutil/trace.go:171","msg":"trace[1681662313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:347; }","duration":"445.225762ms","start":"2026-02-12T00:54:51.051937Z","end":"2026-02-12T00:54:51.497163Z","steps":["trace[1681662313] 'agreement among raft nodes before linearized reading' (duration: 444.793808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:51.498222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:51.051922Z","time spent":"446.280091ms","remote":"127.0.0.1:34560","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-12T00:54:51.758131Z","caller":"traceutil/trace.go:171","msg":"trace[267409493] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"252.423578ms","start":"2026-02-12T00:54:51.505691Z","end":"2026-02-12T00:54:51.758115Z","steps":["trace[267409493] 'process raft request' (duration: 252.024685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:53.990579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.145916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:54:53.990987Z","caller":"traceutil/trace.go:171","msg":"trace[329281032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:359; }","duration":"119.611619ms","start":"2026-02-12T00:54:53.871357Z","end":"2026-02-12T00:54:53.990968Z","steps":["trace[329281032] 'range keys from in-memory index tree' (duration: 118.56312ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:54:54.206925Z","caller":"traceutil/trace.go:171","msg":"trace[318074016] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"204.767736ms","start":"2026-02-12T00:54:54.00214Z","end":"2026-02-12T00:54:54.206908Z","steps":["trace[318074016] 'process raft request' (duration: 204.679395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:54.691955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.164007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T00:54:54.692045Z","caller":"traceutil/trace.go:171","msg":"trace[135058353] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:374; }","duration":"229.050194ms","start":"2026-02-12T00:54:54.46298Z","end":"2026-02-12T00:54:54.69203Z","steps":["trace[135058353] 'read index received' (duration: 31.681144ms)","trace[135058353] 'applied index is now lower than readState.Index' (duration: 197.36764ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:54.6921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.118884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:54:54.692097Z","caller":"traceutil/trace.go:171","msg":"trace[713930579] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"470.041283ms","start":"2026-02-12T00:54:54.22204Z","end":"2026-02-12T00:54:54.692082Z","steps":["trace[713930579] 'process raft request' (duration: 272.679154ms)","trace[713930579] 'compare' (duration: 197.042816ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:54:54.692124Z","caller":"traceutil/trace.go:171","msg":"trace[1109350606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:361; }","duration":"229.176914ms","start":"2026-02-12T00:54:54.462939Z","end":"2026-02-12T00:54:54.692116Z","steps":["trace[1109350606] 'agreement among raft nodes before linearized reading' (duration: 229.132834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:54.69215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:54.222025Z","time spent":"470.099024ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7021,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T00:54:57.557728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114747060609088,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T00:54:57.782961Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.180695189s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T00:54:58.603307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.119305ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-12T00:54:58.603495Z","caller":"traceutil/trace.go:171","msg":"trace[1234874956] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"1.546560256s","start":"2026-02-12T00:54:57.056865Z","end":"2026-02-12T00:54:58.603426Z","steps":["trace[1234874956] 'read index received' (duration: 726.31038ms)","trace[1234874956] 'applied index is now lower than readState.Index' (duration: 820.247956ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:54:58.603614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546737038s","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-12T00:54:58.603644Z","caller":"traceutil/trace.go:171","msg":"trace[1148835705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:367; }","duration":"1.546811349s","start":"2026-02-12T00:54:57.056823Z","end":"2026-02-12T00:54:58.603634Z","steps":["trace[1148835705] 'agreement among raft nodes before linearized reading' (duration: 1.546728068s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:58.603677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:57.056801Z","time spent":"1.546867849s","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T00:54:58.604265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121285164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:54:58.604299Z","caller":"traceutil/trace.go:171","msg":"trace[119066020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"1.121380125s","start":"2026-02-12T00:54:57.482909Z","end":"2026-02-12T00:54:58.604289Z","steps":["trace[119066020] 'agreement among raft nodes before linearized reading' (duration: 1.121331145s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:58.604326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:57.482888Z","time spent":"1.121431286s","remote":"127.0.0.1:34272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T00:54:58.604507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.940333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-12T00:54:58.604535Z","caller":"traceutil/trace.go:171","msg":"trace[2016950423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:367; }","duration":"745.011574ms","start":"2026-02-12T00:54:57.859515Z","end":"2026-02-12T00:54:58.604526Z","steps":["trace[2016950423] 'agreement among raft nodes before linearized reading' (duration: 744.962704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:58.60456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:57.859493Z","time spent":"745.061095ms","remote":"127.0.0.1:34560","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-12T00:54:58.607785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05818864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T00:54:58.607912Z","caller":"traceutil/trace.go:171","msg":"trace[257593878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:367; }","duration":"1.058369641s","start":"2026-02-12T00:54:57.54953Z","end":"2026-02-12T00:54:58.607899Z","steps":["trace[257593878] 'agreement among raft nodes before linearized reading' (duration: 1.058185369s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:58.608016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:57.549509Z","time spent":"1.058492902s","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T00:54:58.609267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04239026s","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-12T00:54:58.609328Z","caller":"traceutil/trace.go:171","msg":"trace[2056170600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:367; }","duration":"1.04247205s","start":"2026-02-12T00:54:57.566848Z","end":"2026-02-12T00:54:58.60932Z","steps":["trace[2056170600] 'agreement among raft nodes before linearized reading' (duration: 1.03780761s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:54:58.609371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:54:57.566837Z","time spent":"1.042526811s","remote":"127.0.0.1:34560","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-12T00:55:03.581884Z","caller":"traceutil/trace.go:171","msg":"trace[828517932] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"187.874399ms","start":"2026-02-12T00:55:03.393907Z","end":"2026-02-12T00:55:03.581781Z","steps":["trace[828517932] 'process raft request' (duration: 187.709418ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:03.582116Z","caller":"traceutil/trace.go:171","msg":"trace[1321003580] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:399; }","duration":"117.297328ms","start":"2026-02-12T00:55:03.464805Z","end":"2026-02-12T00:55:03.582103Z","steps":["trace[1321003580] 'read index received' (duration: 117.291438ms)","trace[1321003580] 'applied index is now lower than readState.Index' (duration: 4.75µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:55:03.582327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.443119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:55:03.582364Z","caller":"traceutil/trace.go:171","msg":"trace[1132890918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:383; }","duration":"117.630751ms","start":"2026-02-12T00:55:03.464723Z","end":"2026-02-12T00:55:03.582354Z","steps":["trace[1132890918] 'agreement among raft nodes before linearized reading' (duration: 117.422189ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:03.832582Z","caller":"traceutil/trace.go:171","msg":"trace[756683460] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"116.014367ms","start":"2026-02-12T00:55:03.716521Z","end":"2026-02-12T00:55:03.832535Z","steps":["trace[756683460] 'process raft request' (duration: 96.781923ms)","trace[756683460] 'compare' (duration: 18.880391ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:55:03.833044Z","caller":"traceutil/trace.go:171","msg":"trace[336528076] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"110.974954ms","start":"2026-02-12T00:55:03.72205Z","end":"2026-02-12T00:55:03.833025Z","steps":["trace[336528076] 'process raft request' (duration: 110.214718ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:03.833525Z","caller":"traceutil/trace.go:171","msg":"trace[298794429] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"111.235867ms","start":"2026-02-12T00:55:03.722099Z","end":"2026-02-12T00:55:03.833335Z","steps":["trace[298794429] 'process raft request' (duration: 110.189308ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:03.835464Z","caller":"traceutil/trace.go:171","msg":"trace[2476277] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"113.233694ms","start":"2026-02-12T00:55:03.722215Z","end":"2026-02-12T00:55:03.835448Z","steps":["trace[2476277] 'process raft request' (duration: 110.092607ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:04.023601Z","caller":"traceutil/trace.go:171","msg":"trace[271463955] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"107.921377ms","start":"2026-02-12T00:55:03.915663Z","end":"2026-02-12T00:55:04.023585Z","steps":["trace[271463955] 'process raft request' (duration: 80.697546ms)","trace[271463955] 'compare' (duration: 26.889128ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:55:04.02378Z","caller":"traceutil/trace.go:171","msg":"trace[694549841] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"103.363099ms","start":"2026-02-12T00:55:03.920412Z","end":"2026-02-12T00:55:04.023775Z","steps":["trace[694549841] 'process raft request' (duration: 102.933855ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:17.627453Z","caller":"traceutil/trace.go:171","msg":"trace[1557907629] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"333.13607ms","start":"2026-02-12T00:55:17.294293Z","end":"2026-02-12T00:55:17.627429Z","steps":["trace[1557907629] 'process raft request' (duration: 332.882768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:17.627596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:55:17.294274Z","time spent":"333.252371ms","remote":"127.0.0.1:34480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4210,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T00:55:17.627838Z","caller":"traceutil/trace.go:171","msg":"trace[459098282] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:501; }","duration":"167.931407ms","start":"2026-02-12T00:55:17.459896Z","end":"2026-02-12T00:55:17.627828Z","steps":["trace[459098282] 'read index received' (duration: 167.927457ms)","trace[459098282] 'applied index is now lower than readState.Index' (duration: 3.15µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:55:17.627913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.011767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:55:17.627955Z","caller":"traceutil/trace.go:171","msg":"trace[241733815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:480; }","duration":"168.081187ms","start":"2026-02-12T00:55:17.459863Z","end":"2026-02-12T00:55:17.627944Z","steps":["trace[241733815] 'agreement among raft nodes before linearized reading' (duration: 167.998947ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:19.133542Z","caller":"traceutil/trace.go:171","msg":"trace[1960343845] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"148.935747ms","start":"2026-02-12T00:55:18.984601Z","end":"2026-02-12T00:55:19.133536Z","steps":["trace[1960343845] 'process raft request' (duration: 148.678725ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:19.133377Z","caller":"traceutil/trace.go:171","msg":"trace[159946870] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"131.093664ms","start":"2026-02-12T00:55:19.002267Z","end":"2026-02-12T00:55:19.133361Z","steps":["trace[159946870] 'read index received' (duration: 130.962823ms)","trace[159946870] 'applied index is now lower than readState.Index' (duration: 130.411µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:55:19.133801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.773066ms","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-12T00:55:19.133818Z","caller":"traceutil/trace.go:171","msg":"trace[866205239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:481; }","duration":"128.812086ms","start":"2026-02-12T00:55:19.005Z","end":"2026-02-12T00:55:19.133812Z","steps":["trace[866205239] 'agreement among raft nodes before linearized reading' (duration: 128.748256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:19.133898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.169811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T00:55:19.13391Z","caller":"traceutil/trace.go:171","msg":"trace[767706556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"128.182151ms","start":"2026-02-12T00:55:19.005725Z","end":"2026-02-12T00:55:19.133907Z","steps":["trace[767706556] 'agreement among raft nodes before linearized reading' (duration: 128.145941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:19.134039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.77591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-12T00:55:19.134059Z","caller":"traceutil/trace.go:171","msg":"trace[1826478502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:481; }","duration":"131.81268ms","start":"2026-02-12T00:55:19.002242Z","end":"2026-02-12T00:55:19.134054Z","steps":["trace[1826478502] 'agreement among raft nodes before linearized reading' (duration: 131.471187ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:19.479786Z","caller":"traceutil/trace.go:171","msg":"trace[1376426690] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"337.811621ms","start":"2026-02-12T00:55:19.141962Z","end":"2026-02-12T00:55:19.479774Z","steps":["trace[1376426690] 'process raft request' (duration: 335.731434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:19.479866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:55:19.141949Z","time spent":"337.874481ms","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T00:55:19.480055Z","caller":"traceutil/trace.go:171","msg":"trace[1926216658] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"335.10537ms","start":"2026-02-12T00:55:19.144944Z","end":"2026-02-12T00:55:19.48005Z","steps":["trace[1926216658] 'process raft request' (duration: 334.706397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:19.480081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:55:19.144939Z","time spent":"335.12831ms","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T00:55:19.480135Z","caller":"traceutil/trace.go:171","msg":"trace[308926419] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"337.218736ms","start":"2026-02-12T00:55:19.142912Z","end":"2026-02-12T00:55:19.480131Z","steps":["trace[308926419] 'process raft request' (duration: 336.719912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:19.480162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:55:19.142907Z","time spent":"337.241167ms","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T00:55:24.318833Z","caller":"traceutil/trace.go:171","msg":"trace[500768048] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"219.172554ms","start":"2026-02-12T00:55:24.099642Z","end":"2026-02-12T00:55:24.318814Z","steps":["trace[500768048] 'process raft request' (duration: 201.1248ms)","trace[500768048] 'compare' (duration: 17.956483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:55:24.318909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.328373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wzmvj\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-12T00:55:24.31897Z","caller":"traceutil/trace.go:171","msg":"trace[899726028] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wzmvj; range_end:; response_count:1; response_revision:519; }","duration":"499.425034ms","start":"2026-02-12T00:55:23.819529Z","end":"2026-02-12T00:55:24.318954Z","steps":["trace[899726028] 'range keys from in-memory index tree' (duration: 499.142532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:24.319011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:55:23.819512Z","time spent":"499.486565ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-wzmvj\" "} {"level":"info","ts":"2026-02-12T00:55:24.577374Z","caller":"traceutil/trace.go:171","msg":"trace[1988116573] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"114.474749ms","start":"2026-02-12T00:55:24.462884Z","end":"2026-02-12T00:55:24.577358Z","steps":["trace[1988116573] 'read index received' (duration: 114.333537ms)","trace[1988116573] 'applied index is now lower than readState.Index' (duration: 140.622µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:55:24.577518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.616831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:55:24.577542Z","caller":"traceutil/trace.go:171","msg":"trace[780055894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"114.681622ms","start":"2026-02-12T00:55:24.462853Z","end":"2026-02-12T00:55:24.577535Z","steps":["trace[780055894] 'agreement among raft nodes before linearized reading' (duration: 114.614191ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:24.57773Z","caller":"traceutil/trace.go:171","msg":"trace[1815185610] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"249.124129ms","start":"2026-02-12T00:55:24.328599Z","end":"2026-02-12T00:55:24.577723Z","steps":["trace[1815185610] 'process raft request' (duration: 248.641683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:26.022563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114747060609403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T00:55:26.522914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114747060609403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T00:55:26.84892Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.326818148s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T00:55:26.849345Z","caller":"traceutil/trace.go:171","msg":"trace[1664738962] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.327282278s","start":"2026-02-12T00:55:25.522044Z","end":"2026-02-12T00:55:26.849326Z","steps":["trace[1664738962] 'process raft request' (duration: 1.327190434s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:26.849441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:55:25.522032Z","time spent":"1.327358471s","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T00:55:26.849768Z","caller":"traceutil/trace.go:171","msg":"trace[770283646] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:545; }","duration":"1.327288608s","start":"2026-02-12T00:55:25.522472Z","end":"2026-02-12T00:55:26.849761Z","steps":["trace[770283646] 'read index received' (duration: 1.327286338s)","trace[770283646] 'applied index is now lower than readState.Index' (duration: 1.93µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:55:26.849846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327370032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T00:55:26.84986Z","caller":"traceutil/trace.go:171","msg":"trace[1988937494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"1.327397152s","start":"2026-02-12T00:55:25.522458Z","end":"2026-02-12T00:55:26.849855Z","steps":["trace[1988937494] 'agreement among raft nodes before linearized reading' (duration: 1.32733729s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:26.849874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:55:25.522452Z","time spent":"1.327418783s","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T00:55:27.350177Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485114747060609406,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T00:55:27.450282Z","caller":"traceutil/trace.go:171","msg":"trace[1947737813] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"595.974402ms","start":"2026-02-12T00:55:26.854298Z","end":"2026-02-12T00:55:27.450273Z","steps":["trace[1947737813] 'process raft request' (duration: 595.67647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:27.450384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:55:26.854288Z","time spent":"596.037834ms","remote":"127.0.0.1:34560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T00:55:27.450113Z","caller":"traceutil/trace.go:171","msg":"trace[2024410074] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:545; }","duration":"600.315439ms","start":"2026-02-12T00:55:26.84978Z","end":"2026-02-12T00:55:27.450095Z","steps":["trace[2024410074] 'read index received' (duration: 522.357127ms)","trace[2024410074] 'applied index is now lower than readState.Index' (duration: 77.957612ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:55:27.450707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919727613s","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-12T00:55:27.450731Z","caller":"traceutil/trace.go:171","msg":"trace[1954792768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"1.919763465s","start":"2026-02-12T00:55:25.53096Z","end":"2026-02-12T00:55:27.450723Z","steps":["trace[1954792768] 'agreement among raft nodes before linearized reading' (duration: 1.919671931s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:27.450752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:55:25.530953Z","time spent":"1.919792076s","remote":"127.0.0.1:34560","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-12T00:55:27.451374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.915904ms","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-12T00:55:27.451397Z","caller":"traceutil/trace.go:171","msg":"trace[162602236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"268.973347ms","start":"2026-02-12T00:55:27.182417Z","end":"2026-02-12T00:55:27.45139Z","steps":["trace[162602236] 'agreement among raft nodes before linearized reading' (duration: 268.898633ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:37.226994Z","caller":"traceutil/trace.go:171","msg":"trace[1264320880] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"291.839027ms","start":"2026-02-12T00:55:36.935138Z","end":"2026-02-12T00:55:37.226977Z","steps":["trace[1264320880] 'process raft request' (duration: 291.726082ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:37.228496Z","caller":"traceutil/trace.go:171","msg":"trace[1240865110] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"272.176812ms","start":"2026-02-12T00:55:36.956308Z","end":"2026-02-12T00:55:37.228485Z","steps":["trace[1240865110] 'read index received' (duration: 270.711016ms)","trace[1240865110] 'applied index is now lower than readState.Index' (duration: 1.464646ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T00:55:37.22863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.300047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-12T00:55:37.22866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.312817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:55:37.228686Z","caller":"traceutil/trace.go:171","msg":"trace[546657605] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"280.495411ms","start":"2026-02-12T00:55:36.948185Z","end":"2026-02-12T00:55:37.228681Z","steps":["trace[546657605] 'process raft request' (duration: 280.249132ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:37.228686Z","caller":"traceutil/trace.go:171","msg":"trace[1960298855] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:638; }","duration":"272.348859ms","start":"2026-02-12T00:55:36.95633Z","end":"2026-02-12T00:55:37.228679Z","steps":["trace[1960298855] 'agreement among raft nodes before linearized reading' (duration: 272.307197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:37.228801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.445072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T00:55:37.228821Z","caller":"traceutil/trace.go:171","msg":"trace[1643076944] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:638; }","duration":"272.471673ms","start":"2026-02-12T00:55:36.956344Z","end":"2026-02-12T00:55:37.228815Z","steps":["trace[1643076944] 'agreement among raft nodes before linearized reading' (duration: 272.441672ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:37.228667Z","caller":"traceutil/trace.go:171","msg":"trace[1289196056] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:638; }","duration":"272.355749ms","start":"2026-02-12T00:55:36.956305Z","end":"2026-02-12T00:55:37.228661Z","steps":["trace[1289196056] 'agreement among raft nodes before linearized reading' (duration: 272.284607ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:39.331457Z","caller":"traceutil/trace.go:171","msg":"trace[83956058] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"244.383404ms","start":"2026-02-12T00:55:39.087054Z","end":"2026-02-12T00:55:39.331438Z","steps":["trace[83956058] 'process raft request' (duration: 244.109754ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:39.988675Z","caller":"traceutil/trace.go:171","msg":"trace[709615029] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"205.302145ms","start":"2026-02-12T00:55:39.783357Z","end":"2026-02-12T00:55:39.988659Z","steps":["trace[709615029] 'process raft request' (duration: 205.203011ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:40.163446Z","caller":"traceutil/trace.go:171","msg":"trace[601455594] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"163.688138ms","start":"2026-02-12T00:55:39.999741Z","end":"2026-02-12T00:55:40.163429Z","steps":["trace[601455594] 'process raft request' (duration: 148.184379ms)","trace[601455594] 'compare' (duration: 15.286031ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T00:55:40.371406Z","caller":"traceutil/trace.go:171","msg":"trace[779478163] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"343.591476ms","start":"2026-02-12T00:55:40.027785Z","end":"2026-02-12T00:55:40.371377Z","steps":["trace[779478163] 'process raft request' (duration: 343.556945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:40.37152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:55:40.02777Z","time spent":"343.693909ms","remote":"127.0.0.1:34358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T00:55:40.371829Z","caller":"traceutil/trace.go:171","msg":"trace[874751821] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"364.309835ms","start":"2026-02-12T00:55:40.007509Z","end":"2026-02-12T00:55:40.371819Z","steps":["trace[874751821] 'process raft request' (duration: 363.724174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:40.371882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T00:55:40.007492Z","time spent":"364.361847ms","remote":"127.0.0.1:34780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T00:55:40.46005Z","caller":"traceutil/trace.go:171","msg":"trace[1085728807] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"279.977762ms","start":"2026-02-12T00:55:40.180048Z","end":"2026-02-12T00:55:40.460026Z","steps":["trace[1085728807] 'process raft request' (duration: 279.835817ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:49.550683Z","caller":"traceutil/trace.go:171","msg":"trace[2024882200] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"103.080664ms","start":"2026-02-12T00:55:49.447588Z","end":"2026-02-12T00:55:49.550669Z","steps":["trace[2024882200] 'process raft request' (duration: 102.985541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:49.700683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.159257ms","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-12T00:55:49.700741Z","caller":"traceutil/trace.go:171","msg":"trace[123394908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:735; }","duration":"109.24457ms","start":"2026-02-12T00:55:49.591484Z","end":"2026-02-12T00:55:49.700729Z","steps":["trace[123394908] 'range keys from in-memory index tree' (duration: 109.089915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:55:49.700832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.396285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T00:55:49.700846Z","caller":"traceutil/trace.go:171","msg":"trace[816465008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"100.431796ms","start":"2026-02-12T00:55:49.60041Z","end":"2026-02-12T00:55:49.700841Z","steps":["trace[816465008] 'range keys from in-memory index tree' (duration: 100.328043ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:55:50.858753Z","caller":"traceutil/trace.go:171","msg":"trace[161832101] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"100.854826ms","start":"2026-02-12T00:55:50.75788Z","end":"2026-02-12T00:55:50.858735Z","steps":["trace[161832101] 'process raft request' (duration: 100.751882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:56:50.716884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.854978ms","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-12T00:56:50.716977Z","caller":"traceutil/trace.go:171","msg":"trace[879142165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"280.95554ms","start":"2026-02-12T00:56:50.436005Z","end":"2026-02-12T00:56:50.716961Z","steps":["trace[879142165] 'range keys from in-memory index tree' (duration: 280.724507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T00:56:50.717193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.819781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T00:56:50.717221Z","caller":"traceutil/trace.go:171","msg":"trace[1433961463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"242.872552ms","start":"2026-02-12T00:56:50.47434Z","end":"2026-02-12T00:56:50.717212Z","steps":["trace[1433961463] 'range keys from in-memory index tree' (duration: 242.73817ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:56:50.832793Z","caller":"traceutil/trace.go:171","msg":"trace[1220175473] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"110.592953ms","start":"2026-02-12T00:56:50.722178Z","end":"2026-02-12T00:56:50.832771Z","steps":["trace[1220175473] 'process raft request' (duration: 110.42737ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:56:50.833724Z","caller":"traceutil/trace.go:171","msg":"trace[1905811289] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"109.384759ms","start":"2026-02-12T00:56:50.724326Z","end":"2026-02-12T00:56:50.833711Z","steps":["trace[1905811289] 'process raft request' (duration: 109.129884ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:56:58.414698Z","caller":"traceutil/trace.go:171","msg":"trace[1791992893] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"101.165407ms","start":"2026-02-12T00:56:58.313508Z","end":"2026-02-12T00:56:58.414674Z","steps":["trace[1791992893] 'process raft request' (duration: 101.037744ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T00:56:58.429596Z","caller":"traceutil/trace.go:171","msg":"trace[316981440] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"114.545946ms","start":"2026-02-12T00:56:58.315034Z","end":"2026-02-12T00:56:58.42958Z","steps":["trace[316981440] 'process raft request' (duration: 114.447984ms)"],"step_count":1}