{"level":"warn","ts":"2026-02-17T19:20:32.617049Z","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-17T19:20:32.617157Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-17T19:20:32.617243Z","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-17T19:20:32.617266Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-17T19:20:32.617283Z","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-17T19:20:32.62298Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-02-17T19:20:32.623062Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-17T19:20:32.629842Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.569976ms"} {"level":"info","ts":"2026-02-17T19:20:32.655049Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-02-17T19:20:32.655098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T19:20:32.655114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-02-17T19:20:32.655122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T19:20:32.65513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-02-17T19:20:32.655156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-02-17T19:20:32.660678Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T19:20:32.665109Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T19:20:32.669076Z","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-17T19:20:32.673235Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T19:20:32.67358Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T19:20:32.67371Z","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-17T19:20:32.673748Z","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-17T19:20:32.673757Z","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-17T19:20:32.674219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-02-17T19:20:32.674303Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-17T19:20:32.687982Z","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-17T19:20:32.688107Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-17T19:20:32.688121Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-17T19:20:32.689172Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T19:20:32.689197Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T19:20:32.855997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T19:20:32.856029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T19:20:32.856045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-02-17T19:20:32.856064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-02-17T19:20:32.85607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-17T19:20:32.856079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-02-17T19:20:32.85609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-17T19:20:32.858174Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:20:32.85937Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T19:20:32.859464Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T19:20:32.859753Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T19:20:32.862349Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T19:20:32.862367Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T19:20:32.862394Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:20:32.862441Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:20:32.862455Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:20:32.867207Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T19:20:32.887399Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-02-17T19:20:47.412682Z","caller":"traceutil/trace.go:171","msg":"trace[470752949] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"324.494956ms","start":"2026-02-17T19:20:47.088172Z","end":"2026-02-17T19:20:47.412667Z","steps":["trace[470752949] 'read index received' (duration: 323.822344ms)","trace[470752949] 'applied index is now lower than readState.Index' (duration: 671.992µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:20:47.412907Z","caller":"traceutil/trace.go:171","msg":"trace[2047023719] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"511.118413ms","start":"2026-02-17T19:20:46.90178Z","end":"2026-02-17T19:20:47.412899Z","steps":["trace[2047023719] 'process raft request' (duration: 510.264415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:20:47.413238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:20:46.901752Z","time spent":"511.175365ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5255,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:20:47.413395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.22082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T19:20:47.41342Z","caller":"traceutil/trace.go:171","msg":"trace[1193078467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"325.284571ms","start":"2026-02-17T19:20:47.088129Z","end":"2026-02-17T19:20:47.413414Z","steps":["trace[1193078467] 'agreement among raft nodes before linearized reading' (duration: 325.23916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:20:47.413439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:20:47.08811Z","time spent":"325.324683ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:20:47.413538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.138656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T19:20:47.413554Z","caller":"traceutil/trace.go:171","msg":"trace[1716737961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"325.181978ms","start":"2026-02-17T19:20:47.088367Z","end":"2026-02-17T19:20:47.413549Z","steps":["trace[1716737961] 'agreement among raft nodes before linearized reading' (duration: 325.151087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:20:47.413569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:20:47.088351Z","time spent":"325.214839ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T19:20:53.05543Z","caller":"traceutil/trace.go:171","msg":"trace[1097255673] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"132.452449ms","start":"2026-02-17T19:20:52.922939Z","end":"2026-02-17T19:20:53.055391Z","steps":["trace[1097255673] 'read index received' (duration: 132.16888ms)","trace[1097255673] 'applied index is now lower than readState.Index' (duration: 282.749µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:20:53.05561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.672167ms","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-17T19:20:53.055666Z","caller":"traceutil/trace.go:171","msg":"trace[1686572657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:420; }","duration":"132.77956ms","start":"2026-02-17T19:20:52.922876Z","end":"2026-02-17T19:20:53.055656Z","steps":["trace[1686572657] 'agreement among raft nodes before linearized reading' (duration: 132.640425ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:20:53.055721Z","caller":"traceutil/trace.go:171","msg":"trace[581477510] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"213.970536ms","start":"2026-02-17T19:20:52.841729Z","end":"2026-02-17T19:20:53.055699Z","steps":["trace[581477510] 'process raft request' (duration: 213.448389ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:05.178393Z","caller":"traceutil/trace.go:171","msg":"trace[1855669213] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"132.707578ms","start":"2026-02-17T19:21:05.045669Z","end":"2026-02-17T19:21:05.178377Z","steps":["trace[1855669213] 'process raft request' (duration: 132.519552ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:05.997605Z","caller":"traceutil/trace.go:171","msg":"trace[940060108] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"352.33277ms","start":"2026-02-17T19:21:05.645248Z","end":"2026-02-17T19:21:05.997581Z","steps":["trace[940060108] 'process raft request' (duration: 351.388889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:05.997937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:05.645222Z","time spent":"352.655151ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:21:06.623703Z","caller":"traceutil/trace.go:171","msg":"trace[363684401] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"776.384894ms","start":"2026-02-17T19:21:05.846894Z","end":"2026-02-17T19:21:06.623279Z","steps":["trace[363684401] 'process raft request' (duration: 776.222549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:06.623831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:05.846878Z","time spent":"776.894391ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:21:06.811051Z","caller":"traceutil/trace.go:171","msg":"trace[488298828] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"809.704739ms","start":"2026-02-17T19:21:06.001327Z","end":"2026-02-17T19:21:06.811031Z","steps":["trace[488298828] 'process raft request' (duration: 809.44449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:06.81116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:06.001309Z","time spent":"809.793791ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:21:06.811481Z","caller":"traceutil/trace.go:171","msg":"trace[634013359] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:531; }","duration":"413.896652ms","start":"2026-02-17T19:21:06.397568Z","end":"2026-02-17T19:21:06.811465Z","steps":["trace[634013359] 'read index received' (duration: 225.753393ms)","trace[634013359] 'applied index is now lower than readState.Index' (duration: 188.142369ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:06.81171Z","caller":"traceutil/trace.go:171","msg":"trace[997439812] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"562.43713ms","start":"2026-02-17T19:21:06.249263Z","end":"2026-02-17T19:21:06.8117Z","steps":["trace[997439812] 'process raft request' (duration: 561.678315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:06.81175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:06.249242Z","time spent":"562.489652ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:21:06.811837Z","caller":"traceutil/trace.go:171","msg":"trace[1379707674] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"766.109938ms","start":"2026-02-17T19:21:06.045708Z","end":"2026-02-17T19:21:06.811818Z","steps":["trace[1379707674] 'process raft request' (duration: 765.189418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:06.811868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:06.045695Z","time spent":"766.16041ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T19:21:06.812018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.45256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-17T19:21:06.816114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.356177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T19:21:06.816208Z","caller":"traceutil/trace.go:171","msg":"trace[223338838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:520; }","duration":"333.480271ms","start":"2026-02-17T19:21:06.482716Z","end":"2026-02-17T19:21:06.816196Z","steps":["trace[223338838] 'agreement among raft nodes before linearized reading' (duration: 333.294185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:06.816274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:06.482701Z","time spent":"333.565424ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T19:21:06.816529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.735245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:06.816695Z","caller":"traceutil/trace.go:171","msg":"trace[1058689350] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:520; }","duration":"173.923551ms","start":"2026-02-17T19:21:06.64276Z","end":"2026-02-17T19:21:06.816683Z","steps":["trace[1058689350] 'agreement among raft nodes before linearized reading' (duration: 173.741255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:06.8169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.131037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:06.81695Z","caller":"traceutil/trace.go:171","msg":"trace[2029451638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"198.23326ms","start":"2026-02-17T19:21:06.61871Z","end":"2026-02-17T19:21:06.816943Z","steps":["trace[2029451638] 'agreement among raft nodes before linearized reading' (duration: 198.150997ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:06.81752Z","caller":"traceutil/trace.go:171","msg":"trace[1760721106] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:520; }","duration":"419.974211ms","start":"2026-02-17T19:21:06.397526Z","end":"2026-02-17T19:21:06.8175Z","steps":["trace[1760721106] 'agreement among raft nodes before linearized reading' (duration: 414.429649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:06.817944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:06.397506Z","time spent":"420.405876ms","remote":"127.0.0.1:33136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"info","ts":"2026-02-17T19:21:06.81807Z","caller":"traceutil/trace.go:171","msg":"trace[2024082027] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"325.315173ms","start":"2026-02-17T19:21:06.492746Z","end":"2026-02-17T19:21:06.818061Z","steps":["trace[2024082027] 'process raft request' (duration: 318.285942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:06.818365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:06.492727Z","time spent":"325.606743ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:21:07.539122Z","caller":"traceutil/trace.go:171","msg":"trace[889962375] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"338.490006ms","start":"2026-02-17T19:21:07.200616Z","end":"2026-02-17T19:21:07.539106Z","steps":["trace[889962375] 'read index received' (duration: 338.191156ms)","trace[889962375] 'applied index is now lower than readState.Index' (duration: 298.16µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:07.539385Z","caller":"traceutil/trace.go:171","msg":"trace[712231259] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"351.674298ms","start":"2026-02-17T19:21:07.187703Z","end":"2026-02-17T19:21:07.539377Z","steps":["trace[712231259] 'process raft request' (duration: 351.176602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:07.539459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:07.187686Z","time spent":"351.721419ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:21:07.539617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.998532ms","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-17T19:21:07.539637Z","caller":"traceutil/trace.go:171","msg":"trace[1333173185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"339.039374ms","start":"2026-02-17T19:21:07.200591Z","end":"2026-02-17T19:21:07.53963Z","steps":["trace[1333173185] 'agreement among raft nodes before linearized reading' (duration: 338.969472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:07.539655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:07.20058Z","time spent":"339.070554ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T19:21:07.539777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.115425ms","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-17T19:21:07.539799Z","caller":"traceutil/trace.go:171","msg":"trace[191343353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"339.146757ms","start":"2026-02-17T19:21:07.200647Z","end":"2026-02-17T19:21:07.539794Z","steps":["trace[191343353] 'agreement among raft nodes before linearized reading' (duration: 339.095145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:07.539816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:07.200643Z","time spent":"339.169558ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:21:07.540734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.681026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-17T19:21:07.540762Z","caller":"traceutil/trace.go:171","msg":"trace[1996836032] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:531; }","duration":"297.736828ms","start":"2026-02-17T19:21:07.243018Z","end":"2026-02-17T19:21:07.540754Z","steps":["trace[1996836032] 'agreement among raft nodes before linearized reading' (duration: 297.676196ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:07.663559Z","caller":"traceutil/trace.go:171","msg":"trace[1900564534] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"104.460021ms","start":"2026-02-17T19:21:07.559085Z","end":"2026-02-17T19:21:07.663545Z","steps":["trace[1900564534] 'process raft request' (duration: 98.33407ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:07.663751Z","caller":"traceutil/trace.go:171","msg":"trace[439367290] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"104.689797ms","start":"2026-02-17T19:21:07.559055Z","end":"2026-02-17T19:21:07.663745Z","steps":["trace[439367290] 'process raft request' (duration: 104.385457ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:07.663947Z","caller":"traceutil/trace.go:171","msg":"trace[1290793257] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"104.75933ms","start":"2026-02-17T19:21:07.559183Z","end":"2026-02-17T19:21:07.663942Z","steps":["trace[1290793257] 'process raft request' (duration: 104.282834ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:07.66406Z","caller":"traceutil/trace.go:171","msg":"trace[1253175557] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"104.849733ms","start":"2026-02-17T19:21:07.559206Z","end":"2026-02-17T19:21:07.664056Z","steps":["trace[1253175557] 'process raft request' (duration: 104.650117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:09.942593Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826745613325215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:21:10.442774Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826745613325215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:21:10.44341Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.106909158s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T19:21:10.44379Z","caller":"traceutil/trace.go:171","msg":"trace[1943748228] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"1.001901s","start":"2026-02-17T19:21:09.44187Z","end":"2026-02-17T19:21:10.443771Z","steps":["trace[1943748228] 'read index received' (duration: 1.001713584s)","trace[1943748228] 'applied index is now lower than readState.Index' (duration: 186.406µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:10.444046Z","caller":"traceutil/trace.go:171","msg":"trace[1131395491] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.107594961s","start":"2026-02-17T19:21:09.33643Z","end":"2026-02-17T19:21:10.444025Z","steps":["trace[1131395491] 'process raft request' (duration: 1.107206628s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:10.444156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:09.336409Z","time spent":"1.107665663s","remote":"127.0.0.1:33262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:21:10.444369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002482579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:10.444404Z","caller":"traceutil/trace.go:171","msg":"trace[1139824350] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:542; }","duration":"1.002545511s","start":"2026-02-17T19:21:09.441847Z","end":"2026-02-17T19:21:10.444393Z","steps":["trace[1139824350] 'agreement among raft nodes before linearized reading' (duration: 1.002476689s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:10.444444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:09.441824Z","time spent":"1.002611624s","remote":"127.0.0.1:53002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"warn","ts":"2026-02-17T19:21:10.444571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002171369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:10.444664Z","caller":"traceutil/trace.go:171","msg":"trace[1957259543] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:542; }","duration":"1.002308154s","start":"2026-02-17T19:21:09.442339Z","end":"2026-02-17T19:21:10.444647Z","steps":["trace[1957259543] 'agreement among raft nodes before linearized reading' (duration: 1.002096247s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:10.444733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:09.442324Z","time spent":"1.002396846s","remote":"127.0.0.1:53002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"warn","ts":"2026-02-17T19:21:10.444922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.822867ms","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-17T19:21:10.44496Z","caller":"traceutil/trace.go:171","msg":"trace[676624174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:542; }","duration":"775.870489ms","start":"2026-02-17T19:21:09.669077Z","end":"2026-02-17T19:21:10.444948Z","steps":["trace[676624174] 'agreement among raft nodes before linearized reading' (duration: 775.759315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:10.444987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:09.669071Z","time spent":"775.90935ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:21:10.445118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.078276ms","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-17T19:21:10.445172Z","caller":"traceutil/trace.go:171","msg":"trace[1043030383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"776.156579ms","start":"2026-02-17T19:21:09.669005Z","end":"2026-02-17T19:21:10.445162Z","steps":["trace[1043030383] 'agreement among raft nodes before linearized reading' (duration: 775.994303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:10.445178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.834375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T19:21:10.445209Z","caller":"traceutil/trace.go:171","msg":"trace[730158776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:542; }","duration":"774.879136ms","start":"2026-02-17T19:21:09.670322Z","end":"2026-02-17T19:21:10.445201Z","steps":["trace[730158776] 'agreement among raft nodes before linearized reading' (duration: 774.790553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:10.445237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:09.670291Z","time spent":"774.936888ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:21:10.4452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:09.668989Z","time spent":"776.20473ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T19:21:10.445382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.582283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:10.445413Z","caller":"traceutil/trace.go:171","msg":"trace[618504271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"829.672226ms","start":"2026-02-17T19:21:09.615731Z","end":"2026-02-17T19:21:10.445403Z","steps":["trace[618504271] 'agreement among raft nodes before linearized reading' (duration: 829.603483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:10.445461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:09.615714Z","time spent":"829.741197ms","remote":"127.0.0.1:33114","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-17T19:21:10.445658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003290096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:10.445711Z","caller":"traceutil/trace.go:171","msg":"trace[1001131085] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:542; }","duration":"1.003328708s","start":"2026-02-17T19:21:09.44235Z","end":"2026-02-17T19:21:10.445678Z","steps":["trace[1001131085] 'agreement among raft nodes before linearized reading' (duration: 1.003282426s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:10.445739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:09.442347Z","time spent":"1.003384439s","remote":"127.0.0.1:53002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:21:10.453278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.327421ms","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-17T19:21:10.453425Z","caller":"traceutil/trace.go:171","msg":"trace[1324035304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:542; }","duration":"783.376716ms","start":"2026-02-17T19:21:09.670035Z","end":"2026-02-17T19:21:10.453411Z","steps":["trace[1324035304] 'agreement among raft nodes before linearized reading' (duration: 775.332881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:10.453516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:09.670023Z","time spent":"783.482219ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:21:15.021367Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826745613325266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:21:15.521742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826745613325266,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:21:15.572277Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.052004815s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-17T19:21:15.693157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.576397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:21:15.693258Z","caller":"traceutil/trace.go:171","msg":"trace[776882785] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.210715018s","start":"2026-02-17T19:21:14.482526Z","end":"2026-02-17T19:21:15.693241Z","steps":["trace[776882785] 'process raft request' (duration: 1.089925951s)","trace[776882785] 'compare' (duration: 119.464833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:15.693315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:14.482513Z","time spent":"1.210776579s","remote":"127.0.0.1:33362","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-17T19:21:15.757826Z","caller":"traceutil/trace.go:171","msg":"trace[469724819] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:573; }","duration":"1.237397904s","start":"2026-02-17T19:21:14.520407Z","end":"2026-02-17T19:21:15.757805Z","steps":["trace[469724819] 'read index received' (duration: 1.052050547s)","trace[469724819] 'applied index is now lower than readState.Index' (duration: 185.346467ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:15.757857Z","caller":"traceutil/trace.go:171","msg":"trace[1290911879] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.233665291s","start":"2026-02-17T19:21:14.524173Z","end":"2026-02-17T19:21:15.757838Z","steps":["trace[1290911879] 'process raft request' (duration: 1.233488985s)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:15.757929Z","caller":"traceutil/trace.go:171","msg":"trace[1933233249] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"215.555008ms","start":"2026-02-17T19:21:15.542365Z","end":"2026-02-17T19:21:15.75792Z","steps":["trace[1933233249] 'process raft request' (duration: 215.397003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:15.757991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:14.524163Z","time spent":"1.233754583s","remote":"127.0.0.1:33362","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-17T19:21:15.758243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275761763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T19:21:15.758275Z","caller":"traceutil/trace.go:171","msg":"trace[1239089660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"1.275804705s","start":"2026-02-17T19:21:14.482461Z","end":"2026-02-17T19:21:15.758266Z","steps":["trace[1239089660] 'agreement among raft nodes before linearized reading' (duration: 1.275689091s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:15.758303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:14.482457Z","time spent":"1.275839656s","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:21:15.758356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213206139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zhp2q\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-17T19:21:15.758392Z","caller":"traceutil/trace.go:171","msg":"trace[2072559916] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zhp2q; range_end:; response_count:1; response_revision:559; }","duration":"1.213284312s","start":"2026-02-17T19:21:14.545097Z","end":"2026-02-17T19:21:15.758381Z","steps":["trace[2072559916] 'agreement among raft nodes before linearized reading' (duration: 1.213180078s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:15.758419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:14.545078Z","time spent":"1.213334634s","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-zhp2q\" "} {"level":"warn","ts":"2026-02-17T19:21:15.758682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.961199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-17T19:21:15.758719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140275504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:15.758816Z","caller":"traceutil/trace.go:171","msg":"trace[1238188086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"1.140377947s","start":"2026-02-17T19:21:14.618364Z","end":"2026-02-17T19:21:15.758742Z","steps":["trace[1238188086] 'agreement among raft nodes before linearized reading' (duration: 1.140288474s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:15.758845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:14.618342Z","time spent":"1.140495972s","remote":"127.0.0.1:33118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T19:21:15.758721Z","caller":"traceutil/trace.go:171","msg":"trace[299718679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:559; }","duration":"888.03102ms","start":"2026-02-17T19:21:14.870681Z","end":"2026-02-17T19:21:15.758712Z","steps":["trace[299718679] 'agreement among raft nodes before linearized reading' (duration: 887.935647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:15.759001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:14.87065Z","time spent":"888.341911ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-17T19:21:20.83404Z","caller":"traceutil/trace.go:171","msg":"trace[937105735] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"126.429802ms","start":"2026-02-17T19:21:20.70759Z","end":"2026-02-17T19:21:20.83402Z","steps":["trace[937105735] 'process raft request' (duration: 126.292957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:28.253239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.305105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-17T19:21:28.253391Z","caller":"traceutil/trace.go:171","msg":"trace[400770767] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:640; }","duration":"897.47204ms","start":"2026-02-17T19:21:27.3559Z","end":"2026-02-17T19:21:28.253372Z","steps":["trace[400770767] 'range keys from in-memory index tree' (duration: 897.202281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:28.253542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:27.355895Z","time spent":"897.634875ms","remote":"127.0.0.1:33296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-17T19:21:28.253801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.720544ms","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-17T19:21:28.253967Z","caller":"traceutil/trace.go:171","msg":"trace[613294195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"497.943712ms","start":"2026-02-17T19:21:27.756001Z","end":"2026-02-17T19:21:28.253944Z","steps":["trace[613294195] 'range keys from in-memory index tree' (duration: 497.146565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:28.254123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:27.75598Z","time spent":"498.115747ms","remote":"127.0.0.1:33362","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-17T19:21:28.254836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.96632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-17T19:21:28.255087Z","caller":"traceutil/trace.go:171","msg":"trace[1653807395] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:640; }","duration":"899.229718ms","start":"2026-02-17T19:21:27.355843Z","end":"2026-02-17T19:21:28.255073Z","steps":["trace[1653807395] 'range keys from in-memory index tree' (duration: 898.785103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:28.255256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:27.355824Z","time spent":"899.414944ms","remote":"127.0.0.1:33296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-17T19:21:28.255448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.386033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-17T19:21:28.255563Z","caller":"traceutil/trace.go:171","msg":"trace[1345441587] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:640; }","duration":"899.506377ms","start":"2026-02-17T19:21:27.356045Z","end":"2026-02-17T19:21:28.255552Z","steps":["trace[1345441587] 'range keys from in-memory index tree' (duration: 899.344271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:28.255701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:27.356042Z","time spent":"899.645442ms","remote":"127.0.0.1:33296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-17T19:21:28.256713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.552483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:28.257673Z","caller":"traceutil/trace.go:171","msg":"trace[542217646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"637.514225ms","start":"2026-02-17T19:21:27.620123Z","end":"2026-02-17T19:21:28.257637Z","steps":["trace[542217646] 'range keys from in-memory index tree' (duration: 636.506992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:28.257711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:27.620109Z","time spent":"637.593777ms","remote":"127.0.0.1:33114","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-17T19:21:28.256834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.730729ms","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-17T19:21:28.257936Z","caller":"traceutil/trace.go:171","msg":"trace[1427734282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"422.879736ms","start":"2026-02-17T19:21:27.835008Z","end":"2026-02-17T19:21:28.257888Z","steps":["trace[1427734282] 'range keys from in-memory index tree' (duration: 421.566463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:28.257969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:27.835Z","time spent":"422.958688ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:21:28.257559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.667419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T19:21:28.258154Z","caller":"traceutil/trace.go:171","msg":"trace[1980386221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"423.308821ms","start":"2026-02-17T19:21:27.834837Z","end":"2026-02-17T19:21:28.258146Z","steps":["trace[1980386221] 'range keys from in-memory index tree' (duration: 422.402991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:28.258228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:27.834797Z","time spent":"423.421064ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:21:28.257613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.760437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T19:21:28.258419Z","caller":"traceutil/trace.go:171","msg":"trace[127027351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:640; }","duration":"421.605724ms","start":"2026-02-17T19:21:27.836805Z","end":"2026-02-17T19:21:28.258411Z","steps":["trace[127027351] 'range keys from in-memory index tree' (duration: 420.694375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:28.258582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:27.836795Z","time spent":"421.688788ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-17T19:21:28.680941Z","caller":"traceutil/trace.go:171","msg":"trace[2103208768] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"415.518425ms","start":"2026-02-17T19:21:28.265396Z","end":"2026-02-17T19:21:28.680914Z","steps":["trace[2103208768] 'process raft request' (duration: 415.358979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:28.68108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:28.265385Z","time spent":"415.626049ms","remote":"127.0.0.1:33362","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-17T19:21:29.087425Z","caller":"traceutil/trace.go:171","msg":"trace[590872070] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:662; }","duration":"513.73046ms","start":"2026-02-17T19:21:28.573669Z","end":"2026-02-17T19:21:29.0874Z","steps":["trace[590872070] 'read index received' (duration: 107.341025ms)","trace[590872070] 'applied index is now lower than readState.Index' (duration: 406.388185ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:29.087657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.956717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T19:21:29.087695Z","caller":"traceutil/trace.go:171","msg":"trace[1210052323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"514.04079ms","start":"2026-02-17T19:21:28.573642Z","end":"2026-02-17T19:21:29.087683Z","steps":["trace[1210052323] 'agreement among raft nodes before linearized reading' (duration: 513.857274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:29.087734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:28.573628Z","time spent":"514.096821ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T19:21:29.088244Z","caller":"traceutil/trace.go:171","msg":"trace[257338033] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"807.191307ms","start":"2026-02-17T19:21:28.281036Z","end":"2026-02-17T19:21:29.088227Z","steps":["trace[257338033] 'process raft request' (duration: 806.276147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:29.088338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:28.28103Z","time spent":"807.248049ms","remote":"127.0.0.1:33362","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-17T19:21:29.088408Z","caller":"traceutil/trace.go:171","msg":"trace[1452490429] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"807.267469ms","start":"2026-02-17T19:21:28.281089Z","end":"2026-02-17T19:21:29.088356Z","steps":["trace[1452490429] 'process raft request' (duration: 806.267526ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:29.08849Z","caller":"traceutil/trace.go:171","msg":"trace[476850867] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"807.754275ms","start":"2026-02-17T19:21:28.280729Z","end":"2026-02-17T19:21:29.088483Z","steps":["trace[476850867] 'process raft request' (duration: 806.428392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:29.088532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:28.280717Z","time spent":"807.790726ms","remote":"127.0.0.1:33362","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-17T19:21:29.088584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:28.281085Z","time spent":"807.403693ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:21:39.63243Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826745613325570,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T19:21:39.708579Z","caller":"traceutil/trace.go:171","msg":"trace[1769242088] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:736; }","duration":"576.524162ms","start":"2026-02-17T19:21:39.132035Z","end":"2026-02-17T19:21:39.708559Z","steps":["trace[1769242088] 'read index received' (duration: 576.372697ms)","trace[1769242088] 'applied index is now lower than readState.Index' (duration: 150.815µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:39.708785Z","caller":"traceutil/trace.go:171","msg":"trace[267801741] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"577.507974ms","start":"2026-02-17T19:21:39.13127Z","end":"2026-02-17T19:21:39.708778Z","steps":["trace[267801741] 'process raft request' (duration: 577.183974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:39.708864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:39.13126Z","time spent":"577.546095ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:21:39.709035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.995767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T19:21:39.709056Z","caller":"traceutil/trace.go:171","msg":"trace[872838426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:712; }","duration":"577.030899ms","start":"2026-02-17T19:21:39.132018Z","end":"2026-02-17T19:21:39.709049Z","steps":["trace[872838426] 'agreement among raft nodes before linearized reading' (duration: 576.953627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:39.709074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:39.132012Z","time spent":"577.05764ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:21:39.709178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.466259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T19:21:39.709196Z","caller":"traceutil/trace.go:171","msg":"trace[765410848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:712; }","duration":"551.503141ms","start":"2026-02-17T19:21:39.157687Z","end":"2026-02-17T19:21:39.70919Z","steps":["trace[765410848] 'agreement among raft nodes before linearized reading' (duration: 551.456859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:39.709211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:39.157676Z","time spent":"551.531211ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:21:39.709544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.970053ms","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-17T19:21:39.709569Z","caller":"traceutil/trace.go:171","msg":"trace[1192481042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:712; }","duration":"551.009434ms","start":"2026-02-17T19:21:39.158553Z","end":"2026-02-17T19:21:39.709563Z","steps":["trace[1192481042] 'agreement among raft nodes before linearized reading' (duration: 550.939932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:39.709588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:39.158547Z","time spent":"551.036755ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:21:40.167215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.321267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:21:40.16729Z","caller":"traceutil/trace.go:171","msg":"trace[1855770663] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"453.532548ms","start":"2026-02-17T19:21:39.713744Z","end":"2026-02-17T19:21:40.167277Z","steps":["trace[1855770663] 'process raft request' (duration: 264.084447ms)","trace[1855770663] 'compare' (duration: 189.230604ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:40.167332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:39.713733Z","time spent":"453.580899ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:21:40.25864Z","caller":"traceutil/trace.go:171","msg":"trace[1963089702] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:737; }","duration":"407.89411ms","start":"2026-02-17T19:21:39.850729Z","end":"2026-02-17T19:21:40.258623Z","steps":["trace[1963089702] 'read index received' (duration: 127.108199ms)","trace[1963089702] 'applied index is now lower than readState.Index' (duration: 280.785261ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:40.25876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.010743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:21:40.25879Z","caller":"traceutil/trace.go:171","msg":"trace[613792302] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:716; }","duration":"408.084776ms","start":"2026-02-17T19:21:39.850696Z","end":"2026-02-17T19:21:40.25878Z","steps":["trace[613792302] 'agreement among raft nodes before linearized reading' (duration: 407.993333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:40.258814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:39.850678Z","time spent":"408.129947ms","remote":"127.0.0.1:33452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-17T19:21:40.259154Z","caller":"traceutil/trace.go:171","msg":"trace[1775336958] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"543.466ms","start":"2026-02-17T19:21:39.71568Z","end":"2026-02-17T19:21:40.259146Z","steps":["trace[1775336958] 'process raft request' (duration: 542.81685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:40.259216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:39.715661Z","time spent":"543.516572ms","remote":"127.0.0.1:33362","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-17T19:21:40.259303Z","caller":"traceutil/trace.go:171","msg":"trace[252311807] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"543.566144ms","start":"2026-02-17T19:21:39.715731Z","end":"2026-02-17T19:21:40.259297Z","steps":["trace[252311807] 'process raft request' (duration: 542.847301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:40.259333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:39.715726Z","time spent":"543.590165ms","remote":"127.0.0.1:33362","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-17T19:21:40.259407Z","caller":"traceutil/trace.go:171","msg":"trace[2025806938] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"495.781045ms","start":"2026-02-17T19:21:39.763621Z","end":"2026-02-17T19:21:40.259402Z","steps":["trace[2025806938] 'process raft request' (duration: 494.981939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:40.259463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:39.763604Z","time spent":"495.815905ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:21:42.104294Z","caller":"traceutil/trace.go:171","msg":"trace[483909650] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"487.487958ms","start":"2026-02-17T19:21:41.616773Z","end":"2026-02-17T19:21:42.104261Z","steps":["trace[483909650] 'read index received' (duration: 487.418845ms)","trace[483909650] 'applied index is now lower than readState.Index' (duration: 68.272µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:42.104378Z","caller":"traceutil/trace.go:171","msg":"trace[1540615738] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"803.404138ms","start":"2026-02-17T19:21:41.300966Z","end":"2026-02-17T19:21:42.10437Z","steps":["trace[1540615738] 'process raft request' (duration: 803.173881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:42.104465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:41.300952Z","time spent":"803.45693ms","remote":"127.0.0.1:33262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:21:42.104566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.776917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:42.104616Z","caller":"traceutil/trace.go:171","msg":"trace[1831055119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"487.860479ms","start":"2026-02-17T19:21:41.616742Z","end":"2026-02-17T19:21:42.104603Z","steps":["trace[1831055119] 'agreement among raft nodes before linearized reading' (duration: 487.779827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:42.104653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:41.616727Z","time spent":"487.920642ms","remote":"127.0.0.1:33114","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-17T19:21:42.108635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.279782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:21:42.108668Z","caller":"traceutil/trace.go:171","msg":"trace[1183835096] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:718; }","duration":"177.353374ms","start":"2026-02-17T19:21:41.931306Z","end":"2026-02-17T19:21:42.108659Z","steps":["trace[1183835096] 'agreement among raft nodes before linearized reading' (duration: 177.297132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:42.108784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.972237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-17T19:21:42.108813Z","caller":"traceutil/trace.go:171","msg":"trace[1163568041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:718; }","duration":"393.03997ms","start":"2026-02-17T19:21:41.715765Z","end":"2026-02-17T19:21:42.108805Z","steps":["trace[1163568041] 'agreement among raft nodes before linearized reading' (duration: 392.955838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:42.108834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:41.715745Z","time spent":"393.084221ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-17T19:21:54.311423Z","caller":"traceutil/trace.go:171","msg":"trace[476499710] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"101.308329ms","start":"2026-02-17T19:21:54.210093Z","end":"2026-02-17T19:21:54.311401Z","steps":["trace[476499710] 'read index received' (duration: 101.112652ms)","trace[476499710] 'applied index is now lower than readState.Index' (duration: 194.707µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:54.31179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.66618ms","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-17T19:21:54.311848Z","caller":"traceutil/trace.go:171","msg":"trace[2080931002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:757; }","duration":"101.776353ms","start":"2026-02-17T19:21:54.210046Z","end":"2026-02-17T19:21:54.311822Z","steps":["trace[2080931002] 'agreement among raft nodes before linearized reading' (duration: 101.446912ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:54.317982Z","caller":"traceutil/trace.go:171","msg":"trace[1794264985] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"145.476209ms","start":"2026-02-17T19:21:54.172493Z","end":"2026-02-17T19:21:54.317969Z","steps":["trace[1794264985] 'process raft request' (duration: 138.773773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:55.060782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.603867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:21:55.060966Z","caller":"traceutil/trace.go:171","msg":"trace[1328989947] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:786; }","duration":"441.803869ms","start":"2026-02-17T19:21:54.619151Z","end":"2026-02-17T19:21:55.060955Z","steps":["trace[1328989947] 'read index received' (duration: 210.982645ms)","trace[1328989947] 'applied index is now lower than readState.Index' (duration: 230.820484ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:55.061101Z","caller":"traceutil/trace.go:171","msg":"trace[174745947] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"735.474775ms","start":"2026-02-17T19:21:54.325619Z","end":"2026-02-17T19:21:55.061094Z","steps":["trace[174745947] 'process raft request' (duration: 504.506236ms)","trace[174745947] 'compare' (duration: 230.40044ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:55.061146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:54.325607Z","time spent":"735.518557ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:21:55.061256Z","caller":"traceutil/trace.go:171","msg":"trace[528495251] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"735.399482ms","start":"2026-02-17T19:21:54.325847Z","end":"2026-02-17T19:21:55.061247Z","steps":["trace[528495251] 'process raft request' (duration: 735.00193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:55.061291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:54.325841Z","time spent":"735.433503ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:21:55.061369Z","caller":"traceutil/trace.go:171","msg":"trace[2021260397] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"733.211522ms","start":"2026-02-17T19:21:54.328151Z","end":"2026-02-17T19:21:55.061363Z","steps":["trace[2021260397] 'process raft request' (duration: 732.779898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:55.061403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:54.328143Z","time spent":"733.244923ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:21:55.062013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.346895ms","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-17T19:21:55.062042Z","caller":"traceutil/trace.go:171","msg":"trace[1444386321] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"205.426147ms","start":"2026-02-17T19:21:54.856609Z","end":"2026-02-17T19:21:55.062035Z","steps":["trace[1444386321] 'agreement among raft nodes before linearized reading' (duration: 205.338114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:55.06251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.37038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:55.06254Z","caller":"traceutil/trace.go:171","msg":"trace[2056108012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"443.435852ms","start":"2026-02-17T19:21:54.619098Z","end":"2026-02-17T19:21:55.062533Z","steps":["trace[2056108012] 'agreement among raft nodes before linearized reading' (duration: 442.368377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:55.062648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:54.619079Z","time spent":"443.560196ms","remote":"127.0.0.1:33114","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-17T19:21:55.600458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.697457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:21:55.600564Z","caller":"traceutil/trace.go:171","msg":"trace[1763459868] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"139.382073ms","start":"2026-02-17T19:21:55.461167Z","end":"2026-02-17T19:21:55.600549Z","steps":["trace[1763459868] 'read index received' (duration: 36.831µs)","trace[1763459868] 'applied index is now lower than readState.Index' (duration: 139.344042ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:55.60063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.457205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:21:55.600655Z","caller":"traceutil/trace.go:171","msg":"trace[1347719610] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:761; }","duration":"139.519417ms","start":"2026-02-17T19:21:55.461127Z","end":"2026-02-17T19:21:55.600647Z","steps":["trace[1347719610] 'agreement among raft nodes before linearized reading' (duration: 139.463195ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:55.600661Z","caller":"traceutil/trace.go:171","msg":"trace[1468997187] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"532.941501ms","start":"2026-02-17T19:21:55.067692Z","end":"2026-02-17T19:21:55.600633Z","steps":["trace[1468997187] 'process raft request' (duration: 376.994675ms)","trace[1468997187] 'compare' (duration: 155.613515ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:21:55.60079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:55.067682Z","time spent":"533.039944ms","remote":"127.0.0.1:33362","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-17T19:22:06.57741Z","caller":"traceutil/trace.go:171","msg":"trace[597778690] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"161.509194ms","start":"2026-02-17T19:22:06.415885Z","end":"2026-02-17T19:22:06.577394Z","steps":["trace[597778690] 'process raft request' (duration: 85.129198ms)","trace[597778690] 'compare' (duration: 76.315944ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:22:08.270525Z","caller":"traceutil/trace.go:171","msg":"trace[477668204] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"403.408905ms","start":"2026-02-17T19:22:07.867094Z","end":"2026-02-17T19:22:08.270503Z","steps":["trace[477668204] 'read index received' (duration: 403.210678ms)","trace[477668204] 'applied index is now lower than readState.Index' (duration: 197.307µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:08.270662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.539839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:22:08.270716Z","caller":"traceutil/trace.go:171","msg":"trace[374291158] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:846; }","duration":"403.652812ms","start":"2026-02-17T19:22:07.867054Z","end":"2026-02-17T19:22:08.270706Z","steps":["trace[374291158] 'agreement among raft nodes before linearized reading' (duration: 403.531308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:08.270751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:07.867033Z","time spent":"403.708525ms","remote":"127.0.0.1:33238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-17T19:22:08.271097Z","caller":"traceutil/trace.go:171","msg":"trace[817210803] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"617.805011ms","start":"2026-02-17T19:22:07.653281Z","end":"2026-02-17T19:22:08.271086Z","steps":["trace[817210803] 'process raft request' (duration: 617.083907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:08.271172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:07.653273Z","time spent":"617.849582ms","remote":"127.0.0.1:33362","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-17T19:22:23.76161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.955386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:22:23.761705Z","caller":"traceutil/trace.go:171","msg":"trace[1955938127] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"502.276495ms","start":"2026-02-17T19:22:23.259412Z","end":"2026-02-17T19:22:23.761688Z","steps":["trace[1955938127] 'process raft request' (duration: 401.165013ms)","trace[1955938127] 'compare' (duration: 100.877264ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:23.761759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:23.259402Z","time spent":"502.332617ms","remote":"127.0.0.1:33362","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-17T19:22:23.763422Z","caller":"traceutil/trace.go:171","msg":"trace[546782988] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:923; }","duration":"147.851375ms","start":"2026-02-17T19:22:23.615555Z","end":"2026-02-17T19:22:23.763407Z","steps":["trace[546782988] 'read index received' (duration: 45.011497ms)","trace[546782988] 'applied index is now lower than readState.Index' (duration: 102.839048ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:23.7635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.935509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:22:23.763533Z","caller":"traceutil/trace.go:171","msg":"trace[2121430551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"147.97247ms","start":"2026-02-17T19:22:23.615552Z","end":"2026-02-17T19:22:23.763524Z","steps":["trace[2121430551] 'agreement among raft nodes before linearized reading' (duration: 147.903697ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:23.763739Z","caller":"traceutil/trace.go:171","msg":"trace[813770130] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"504.255398ms","start":"2026-02-17T19:22:23.259474Z","end":"2026-02-17T19:22:23.76373Z","steps":["trace[813770130] 'process raft request' (duration: 503.872856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:23.763794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:23.259458Z","time spent":"504.3042ms","remote":"127.0.0.1:33362","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-17T19:22:26.585379Z","caller":"traceutil/trace.go:171","msg":"trace[2107454256] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"170.651258ms","start":"2026-02-17T19:22:26.414709Z","end":"2026-02-17T19:22:26.585361Z","steps":["trace[2107454256] 'read index received' (duration: 170.538674ms)","trace[2107454256] 'applied index is now lower than readState.Index' (duration: 111.354µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:26.585541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.811073ms","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-17T19:22:26.585562Z","caller":"traceutil/trace.go:171","msg":"trace[2102494062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:894; }","duration":"170.859305ms","start":"2026-02-17T19:22:26.414695Z","end":"2026-02-17T19:22:26.585554Z","steps":["trace[2102494062] 'agreement among raft nodes before linearized reading' (duration: 170.743131ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:26.791617Z","caller":"traceutil/trace.go:171","msg":"trace[1394231203] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"204.943691ms","start":"2026-02-17T19:22:26.586656Z","end":"2026-02-17T19:22:26.7916Z","steps":["trace[1394231203] 'process raft request' (duration: 180.255647ms)","trace[1394231203] 'compare' (duration: 24.576261ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:22:26.823559Z","caller":"traceutil/trace.go:171","msg":"trace[1057991928] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:931; }","duration":"205.418517ms","start":"2026-02-17T19:22:26.618124Z","end":"2026-02-17T19:22:26.823543Z","steps":["trace[1057991928] 'read index received' (duration: 148.799445ms)","trace[1057991928] 'applied index is now lower than readState.Index' (duration: 56.618332ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:22:26.823717Z","caller":"traceutil/trace.go:171","msg":"trace[281812655] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"235.489864ms","start":"2026-02-17T19:22:26.588222Z","end":"2026-02-17T19:22:26.823712Z","steps":["trace[281812655] 'process raft request' (duration: 235.243796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:26.823832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.699255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:22:26.823848Z","caller":"traceutil/trace.go:171","msg":"trace[2043696796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"205.763647ms","start":"2026-02-17T19:22:26.61808Z","end":"2026-02-17T19:22:26.823844Z","steps":["trace[2043696796] 'agreement among raft nodes before linearized reading' (duration: 205.725536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:27.148108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.151002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:22:27.148212Z","caller":"traceutil/trace.go:171","msg":"trace[1172143734] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"319.207317ms","start":"2026-02-17T19:22:26.828985Z","end":"2026-02-17T19:22:27.148193Z","steps":["trace[1172143734] 'process raft request' (duration: 199.882439ms)","trace[1172143734] 'compare' (duration: 119.039509ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:27.148272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:26.828977Z","time spent":"319.267929ms","remote":"127.0.0.1:33362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:22:27.442401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.596489ms","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-17T19:22:27.442449Z","caller":"traceutil/trace.go:171","msg":"trace[525857391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:897; }","duration":"176.655351ms","start":"2026-02-17T19:22:27.265782Z","end":"2026-02-17T19:22:27.442438Z","steps":["trace[525857391] 'range keys from in-memory index tree' (duration: 176.352181ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:27.443614Z","caller":"traceutil/trace.go:171","msg":"trace[2140566886] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"105.174402ms","start":"2026-02-17T19:22:27.337254Z","end":"2026-02-17T19:22:27.442428Z","steps":["trace[2140566886] 'process raft request' (duration: 104.917973ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:40.891175Z","caller":"traceutil/trace.go:171","msg":"trace[307092686] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"240.124423ms","start":"2026-02-17T19:22:40.651037Z","end":"2026-02-17T19:22:40.891161Z","steps":["trace[307092686] 'process raft request' (duration: 239.811273ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:41.007651Z","caller":"traceutil/trace.go:171","msg":"trace[1279997406] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"113.290174ms","start":"2026-02-17T19:22:40.89434Z","end":"2026-02-17T19:22:41.00763Z","steps":["trace[1279997406] 'process raft request' (duration: 111.189187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:25:55.758998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.294907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:25:55.759115Z","caller":"traceutil/trace.go:171","msg":"trace[236211040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1824; }","duration":"138.461713ms","start":"2026-02-17T19:25:55.620617Z","end":"2026-02-17T19:25:55.759079Z","steps":["trace[236211040] 'range keys from in-memory index tree' (duration: 138.240295ms)"],"step_count":1}