{"level":"warn","ts":"2026-02-17T15:59:30.383913Z","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-17T15:59:30.383993Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-17T15:59:30.384086Z","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-17T15:59:30.384116Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-02-17T15:59:30.384155Z","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-17T15:59:30.384776Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-02-17T15:59:30.384869Z","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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-17T15:59:30.390133Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.023299ms"} {"level":"info","ts":"2026-02-17T15:59:30.396515Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-02-17T15:59:30.396586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T15:59:30.396654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-02-17T15:59:30.396662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T15:59:30.396672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-02-17T15:59:30.396725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-02-17T15:59:30.40665Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T15:59:30.408512Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T15:59:30.410701Z","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-17T15:59:30.412684Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T15:59:30.413789Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T15:59:30.413886Z","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-17T15:59:30.413912Z","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-17T15:59:30.41392Z","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-17T15:59:30.41406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-02-17T15:59:30.414116Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-02-17T15:59:30.418281Z","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-17T15:59:30.418419Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T15:59:30.418437Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T15:59:30.418493Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-02-17T15:59:30.418503Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-02-17T15:59:30.797206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T15:59:30.797309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T15:59:30.797347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-02-17T15:59:30.797374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-02-17T15:59:30.797389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-02-17T15:59:30.797412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-02-17T15:59:30.797429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-02-17T15:59:30.818804Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T15:59:30.823218Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T15:59:30.823404Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T15:59:30.823731Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T15:59:30.823834Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T15:59:30.823874Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T15:59:30.827192Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-02-17T15:59:30.827294Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T15:59:30.83839Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T15:59:30.840845Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T15:59:30.840895Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T15:59:37.53363Z","caller":"traceutil/trace.go:171","msg":"trace[1356961212] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:292; }","duration":"123.240073ms","start":"2026-02-17T15:59:37.410374Z","end":"2026-02-17T15:59:37.533614Z","steps":["trace[1356961212] 'read index received' (duration: 37.451886ms)","trace[1356961212] 'applied index is now lower than readState.Index' (duration: 85.787487ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T15:59:37.533792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.400324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-17T15:59:37.533812Z","caller":"traceutil/trace.go:171","msg":"trace[337130264] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:283; }","duration":"123.446215ms","start":"2026-02-17T15:59:37.41036Z","end":"2026-02-17T15:59:37.533807Z","steps":["trace[337130264] 'agreement among raft nodes before linearized reading' (duration: 123.375554ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T15:59:37.534004Z","caller":"traceutil/trace.go:171","msg":"trace[676685295] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"124.726739ms","start":"2026-02-17T15:59:37.409269Z","end":"2026-02-17T15:59:37.533996Z","steps":["trace[676685295] 'process raft request' (duration: 38.604828ms)","trace[676685295] 'compare' (duration: 85.675106ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T15:59:37.707586Z","caller":"traceutil/trace.go:171","msg":"trace[858209897] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:294; }","duration":"116.640633ms","start":"2026-02-17T15:59:37.590927Z","end":"2026-02-17T15:59:37.707567Z","steps":["trace[858209897] 'read index received' (duration: 86.353873ms)","trace[858209897] 'applied index is now lower than readState.Index' (duration: 30.28575ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T15:59:37.70789Z","caller":"traceutil/trace.go:171","msg":"trace[286850202] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"117.551192ms","start":"2026-02-17T15:59:37.590318Z","end":"2026-02-17T15:59:37.707869Z","steps":["trace[286850202] 'process raft request' (duration: 87.00192ms)","trace[286850202] 'compare' (duration: 30.165478ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T15:59:37.710917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.976858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-17T15:59:37.714633Z","caller":"traceutil/trace.go:171","msg":"trace[251697788] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:285; }","duration":"123.696678ms","start":"2026-02-17T15:59:37.590925Z","end":"2026-02-17T15:59:37.714621Z","steps":["trace[251697788] 'agreement among raft nodes before linearized reading' (duration: 116.895526ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T15:59:49.612671Z","caller":"traceutil/trace.go:171","msg":"trace[1669415930] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"643.672486ms","start":"2026-02-17T15:59:48.96898Z","end":"2026-02-17T15:59:49.612653Z","steps":["trace[1669415930] 'process raft request' (duration: 643.568445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T15:59:49.613088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T15:59:48.968948Z","time spent":"643.814487ms","remote":"127.0.0.1:53808","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-17T15:59:49.613451Z","caller":"traceutil/trace.go:171","msg":"trace[376840888] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:424; }","duration":"482.5628ms","start":"2026-02-17T15:59:49.13088Z","end":"2026-02-17T15:59:49.613443Z","steps":["trace[376840888] 'read index received' (duration: 482.5597ms)","trace[376840888] 'applied index is now lower than readState.Index' (duration: 2.55µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T15:59:49.613507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.630021ms","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-17T15:59:49.613526Z","caller":"traceutil/trace.go:171","msg":"trace[761199682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"482.674341ms","start":"2026-02-17T15:59:49.130845Z","end":"2026-02-17T15:59:49.613519Z","steps":["trace[761199682] 'agreement among raft nodes before linearized reading' (duration: 482.63379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T15:59:49.613544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T15:59:49.130816Z","time spent":"482.723501ms","remote":"127.0.0.1:53808","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-17T15:59:50.113882Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119822742773643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T15:59:51.107281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.266222ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T15:59:51.319864Z","caller":"traceutil/trace.go:171","msg":"trace[1780572457] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:424; }","duration":"1.706377813s","start":"2026-02-17T15:59:49.613468Z","end":"2026-02-17T15:59:51.319846Z","steps":["trace[1780572457] 'read index received' (duration: 743.409012ms)","trace[1780572457] 'applied index is now lower than readState.Index' (duration: 962.967981ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T15:59:51.320075Z","caller":"traceutil/trace.go:171","msg":"trace[1362114825] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"1.70281312s","start":"2026-02-17T15:59:49.617254Z","end":"2026-02-17T15:59:51.320067Z","steps":["trace[1362114825] 'process raft request' (duration: 1.702436716s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T15:59:51.320147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T15:59:49.617236Z","time spent":"1.70285915s","remote":"127.0.0.1:53808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T15:59:51.320291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.080888385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-17T15:59:51.320315Z","caller":"traceutil/trace.go:171","msg":"trace[1403754729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"2.080950935s","start":"2026-02-17T15:59:49.239357Z","end":"2026-02-17T15:59:51.320308Z","steps":["trace[1403754729] 'agreement among raft nodes before linearized reading' (duration: 2.080909405s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T15:59:51.320334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T15:59:49.239337Z","time spent":"2.080991536s","remote":"127.0.0.1:53808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T15:59:51.320415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184561881s","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-17T15:59:51.320431Z","caller":"traceutil/trace.go:171","msg":"trace[2089732218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"2.184576801s","start":"2026-02-17T15:59:49.135849Z","end":"2026-02-17T15:59:51.320426Z","steps":["trace[2089732218] 'agreement among raft nodes before linearized reading' (duration: 2.184549251s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T15:59:51.320447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T15:59:49.135841Z","time spent":"2.184602561s","remote":"127.0.0.1:53808","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-17T15:59:51.320543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.936559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T15:59:51.320567Z","caller":"traceutil/trace.go:171","msg":"trace[371985277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"715.005849ms","start":"2026-02-17T15:59:50.605555Z","end":"2026-02-17T15:59:51.320561Z","steps":["trace[371985277] 'agreement among raft nodes before linearized reading' (duration: 714.967269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T15:59:51.320583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T15:59:50.60554Z","time spent":"715.03907ms","remote":"127.0.0.1:53526","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-17T15:59:56.716908Z","caller":"traceutil/trace.go:171","msg":"trace[895099188] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:440; }","duration":"115.317133ms","start":"2026-02-17T15:59:56.601574Z","end":"2026-02-17T15:59:56.716891Z","steps":["trace[895099188] 'read index received' (duration: 115.166832ms)","trace[895099188] 'applied index is now lower than readState.Index' (duration: 149.681µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T15:59:56.717016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.422714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T15:59:56.717037Z","caller":"traceutil/trace.go:171","msg":"trace[1304047738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"115.488385ms","start":"2026-02-17T15:59:56.601542Z","end":"2026-02-17T15:59:56.71703Z","steps":["trace[1304047738] 'agreement among raft nodes before linearized reading' (duration: 115.415725ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T15:59:56.717168Z","caller":"traceutil/trace.go:171","msg":"trace[125287411] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"225.276359ms","start":"2026-02-17T15:59:56.491872Z","end":"2026-02-17T15:59:56.717148Z","steps":["trace[125287411] 'process raft request' (duration: 224.909286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:01.910965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119822742773766,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:00:02.411956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119822742773766,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:00:02.827966Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.675998362s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T16:00:02.828259Z","caller":"traceutil/trace.go:171","msg":"trace[404103262] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"1.417646172s","start":"2026-02-17T16:00:01.410598Z","end":"2026-02-17T16:00:02.828244Z","steps":["trace[404103262] 'read index received' (duration: 1.417518301s)","trace[404103262] 'applied index is now lower than readState.Index' (duration: 127.301µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:00:02.828466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.417854465s","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-17T16:00:02.828535Z","caller":"traceutil/trace.go:171","msg":"trace[1067640292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:470; }","duration":"1.417960196s","start":"2026-02-17T16:00:01.41056Z","end":"2026-02-17T16:00:02.82852Z","steps":["trace[1067640292] 'agreement among raft nodes before linearized reading' (duration: 1.417821535s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:02.828564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141376283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-17T16:00:02.828574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:01.410544Z","time spent":"1.418020106s","remote":"127.0.0.1:53808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T16:00:02.828584Z","caller":"traceutil/trace.go:171","msg":"trace[1079954310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:470; }","duration":"1.141424063s","start":"2026-02-17T16:00:01.687153Z","end":"2026-02-17T16:00:02.828577Z","steps":["trace[1079954310] 'agreement among raft nodes before linearized reading' (duration: 1.141395253s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:02.828609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:01.687139Z","time spent":"1.141465944s","remote":"127.0.0.1:53808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-17T16:00:02.828686Z","caller":"traceutil/trace.go:171","msg":"trace[301295161] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"1.676779588s","start":"2026-02-17T16:00:01.151896Z","end":"2026-02-17T16:00:02.828676Z","steps":["trace[301295161] 'process raft request' (duration: 1.676232393s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:02.828727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21901005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:00:02.828744Z","caller":"traceutil/trace.go:171","msg":"trace[517282280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"1.21902671s","start":"2026-02-17T16:00:01.609712Z","end":"2026-02-17T16:00:02.828739Z","steps":["trace[517282280] 'agreement among raft nodes before linearized reading' (duration: 1.219002079s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:02.828759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:01.609668Z","time spent":"1.219088271s","remote":"127.0.0.1:53526","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-17T16:00:02.828753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:01.151879Z","time spent":"1.676827839s","remote":"127.0.0.1:53696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4210,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:00:02.828862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326828934s","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-17T16:00:02.828879Z","caller":"traceutil/trace.go:171","msg":"trace[2052362709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:470; }","duration":"1.326871074s","start":"2026-02-17T16:00:01.502003Z","end":"2026-02-17T16:00:02.828874Z","steps":["trace[2052362709] 'agreement among raft nodes before linearized reading' (duration: 1.326840514s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:02.828501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355460847s","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-17T16:00:02.828919Z","caller":"traceutil/trace.go:171","msg":"trace[628495073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:470; }","duration":"1.35591785s","start":"2026-02-17T16:00:01.472989Z","end":"2026-02-17T16:00:02.828907Z","steps":["trace[628495073] 'agreement among raft nodes before linearized reading' (duration: 1.355473506s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:02.828953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:01.472968Z","time spent":"1.35597578s","remote":"127.0.0.1:53808","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-17T16:00:02.828894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:01.50199Z","time spent":"1.326900814s","remote":"127.0.0.1:53808","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-17T16:00:11.105615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119822742773858,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T16:00:11.299854Z","caller":"traceutil/trace.go:171","msg":"trace[719510378] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"695.13269ms","start":"2026-02-17T16:00:10.604705Z","end":"2026-02-17T16:00:11.299838Z","steps":["trace[719510378] 'read index received' (duration: 695.030309ms)","trace[719510378] 'applied index is now lower than readState.Index' (duration: 101.661µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:00:11.299936Z","caller":"traceutil/trace.go:171","msg":"trace[1694143192] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"920.470117ms","start":"2026-02-17T16:00:10.379461Z","end":"2026-02-17T16:00:11.299931Z","steps":["trace[1694143192] 'process raft request' (duration: 920.261125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:11.300008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:10.379447Z","time spent":"920.504087ms","remote":"127.0.0.1:53710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:00:11.300096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.396032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:00:11.300113Z","caller":"traceutil/trace.go:171","msg":"trace[1224909922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"695.434402ms","start":"2026-02-17T16:00:10.604673Z","end":"2026-02-17T16:00:11.300108Z","steps":["trace[1224909922] 'agreement among raft nodes before linearized reading' (duration: 695.406862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:11.300126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:10.604648Z","time spent":"695.474603ms","remote":"127.0.0.1:53530","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-17T16:00:11.300308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.299159ms","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-17T16:00:11.30032Z","caller":"traceutil/trace.go:171","msg":"trace[1260100276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"338.340899ms","start":"2026-02-17T16:00:10.961976Z","end":"2026-02-17T16:00:11.300317Z","steps":["trace[1260100276] 'agreement among raft nodes before linearized reading' (duration: 338.279969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:11.300306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.253341ms","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-17T16:00:11.300359Z","caller":"traceutil/trace.go:171","msg":"trace[1959915513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"272.310731ms","start":"2026-02-17T16:00:11.028033Z","end":"2026-02-17T16:00:11.300344Z","steps":["trace[1959915513] 'agreement among raft nodes before linearized reading' (duration: 272.226951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:11.300374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.414459ms","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-17T16:00:11.300387Z","caller":"traceutil/trace.go:171","msg":"trace[1271932486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"274.436699ms","start":"2026-02-17T16:00:11.025947Z","end":"2026-02-17T16:00:11.300384Z","steps":["trace[1271932486] 'agreement among raft nodes before linearized reading' (duration: 274.393008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:11.300332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:10.961959Z","time spent":"338.370049ms","remote":"127.0.0.1:53808","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-17T16:00:11.300495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.634158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-17T16:00:11.300508Z","caller":"traceutil/trace.go:171","msg":"trace[1778039833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"280.671488ms","start":"2026-02-17T16:00:11.019833Z","end":"2026-02-17T16:00:11.300504Z","steps":["trace[1778039833] 'agreement among raft nodes before linearized reading' (duration: 280.635397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:11.883995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.926126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:00:11.884079Z","caller":"traceutil/trace.go:171","msg":"trace[1675938943] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"573.621294ms","start":"2026-02-17T16:00:11.310444Z","end":"2026-02-17T16:00:11.884066Z","steps":["trace[1675938943] 'process raft request' (duration: 58.563347ms)","trace[1675938943] 'compare' (duration: 514.798975ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:00:11.88412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:11.310429Z","time spent":"573.672594ms","remote":"127.0.0.1:53808","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-17T16:00:12.102934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119822742773861,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:00:12.603876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119822742773861,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:00:12.808501Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.439599905s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-17T16:00:12.925114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.233411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:00:12.925238Z","caller":"traceutil/trace.go:171","msg":"trace[1970415391] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.613506127s","start":"2026-02-17T16:00:11.31172Z","end":"2026-02-17T16:00:12.925226Z","steps":["trace[1970415391] 'process raft request' (duration: 1.496941823s)","trace[1970415391] 'compare' (duration: 115.744188ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:00:12.925277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:11.311702Z","time spent":"1.613554997s","remote":"127.0.0.1:53808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:00:12.925511Z","caller":"traceutil/trace.go:171","msg":"trace[907377541] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:533; }","duration":"1.322763288s","start":"2026-02-17T16:00:11.602743Z","end":"2026-02-17T16:00:12.925506Z","steps":["trace[907377541] 'read index received' (duration: 1.205856361s)","trace[907377541] 'applied index is now lower than readState.Index' (duration: 116.906427ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:00:12.92559Z","caller":"traceutil/trace.go:171","msg":"trace[8918965] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.61268127s","start":"2026-02-17T16:00:11.312905Z","end":"2026-02-17T16:00:12.925586Z","steps":["trace[8918965] 'process raft request' (duration: 1.612273117s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:12.925616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:11.312899Z","time spent":"1.61270257s","remote":"127.0.0.1:53808","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-17T16:00:12.925756Z","caller":"traceutil/trace.go:171","msg":"trace[151452692] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.611856793s","start":"2026-02-17T16:00:11.313893Z","end":"2026-02-17T16:00:12.92575Z","steps":["trace[151452692] 'process raft request' (duration: 1.611308369s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:12.925788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:11.313876Z","time spent":"1.611899075s","remote":"127.0.0.1:53808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:00:12.926222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323482164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:00:12.926239Z","caller":"traceutil/trace.go:171","msg":"trace[1360955312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"1.323525524s","start":"2026-02-17T16:00:11.602709Z","end":"2026-02-17T16:00:12.926234Z","steps":["trace[1360955312] 'agreement among raft nodes before linearized reading' (duration: 1.322816699s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:00:12.926253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:00:11.602678Z","time spent":"1.323572635s","remote":"127.0.0.1:53526","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-17T16:00:14.481389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.739548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-17T16:00:14.481468Z","caller":"traceutil/trace.go:171","msg":"trace[653394534] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:525; }","duration":"134.849299ms","start":"2026-02-17T16:00:14.346598Z","end":"2026-02-17T16:00:14.481448Z","steps":["trace[653394534] 'range keys from in-memory index tree' (duration: 134.630797ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:00:14.636636Z","caller":"traceutil/trace.go:171","msg":"trace[1475903211] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"140.255892ms","start":"2026-02-17T16:00:14.496366Z","end":"2026-02-17T16:00:14.636621Z","steps":["trace[1475903211] 'process raft request' (duration: 136.427532ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:00:14.696517Z","caller":"traceutil/trace.go:171","msg":"trace[1219488260] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"124.730879ms","start":"2026-02-17T16:00:14.571773Z","end":"2026-02-17T16:00:14.696504Z","steps":["trace[1219488260] 'process raft request' (duration: 124.490137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:34.548261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.667177ms","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-17T16:01:34.548354Z","caller":"traceutil/trace.go:171","msg":"trace[1760231344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:930; }","duration":"115.785629ms","start":"2026-02-17T16:01:34.432551Z","end":"2026-02-17T16:01:34.548337Z","steps":["trace[1760231344] 'range keys from in-memory index tree' (duration: 115.548564ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:37.861075Z","caller":"traceutil/trace.go:171","msg":"trace[380034518] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"119.97586ms","start":"2026-02-17T16:01:37.741075Z","end":"2026-02-17T16:01:37.861051Z","steps":["trace[380034518] 'process raft request' (duration: 119.038848ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:37.862102Z","caller":"traceutil/trace.go:171","msg":"trace[85544240] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"118.728542ms","start":"2026-02-17T16:01:37.743364Z","end":"2026-02-17T16:01:37.862092Z","steps":["trace[85544240] 'process raft request' (duration: 118.557608ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:38.876252Z","caller":"traceutil/trace.go:171","msg":"trace[647013420] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"100.37203ms","start":"2026-02-17T16:01:38.775856Z","end":"2026-02-17T16:01:38.876228Z","steps":["trace[647013420] 'process raft request' (duration: 99.736076ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:39.389088Z","caller":"traceutil/trace.go:171","msg":"trace[1767276826] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"123.957687ms","start":"2026-02-17T16:01:39.265115Z","end":"2026-02-17T16:01:39.389073Z","steps":["trace[1767276826] 'process raft request' (duration: 123.836954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:41.135457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270606845s","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-17T16:01:41.135899Z","caller":"traceutil/trace.go:171","msg":"trace[1279417814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:953; }","duration":"1.271074695s","start":"2026-02-17T16:01:39.864807Z","end":"2026-02-17T16:01:41.135881Z","steps":["trace[1279417814] 'range keys from in-memory index tree' (duration: 1.270534734s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:41.136715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:39.864793Z","time spent":"1.271915895s","remote":"127.0.0.1:53808","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-17T16:01:41.136173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.172775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T16:01:41.136225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.746279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-17T16:01:41.136346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.389861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-17T16:01:41.136493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.537291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T16:01:41.136498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.167589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T16:01:41.136643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.0562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T16:01:41.136665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.836397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:01:41.137957Z","caller":"traceutil/trace.go:171","msg":"trace[1467868463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"531.989347ms","start":"2026-02-17T16:01:40.605954Z","end":"2026-02-17T16:01:41.137943Z","steps":["trace[1467868463] 'range keys from in-memory index tree' (duration: 530.107673ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:41.138682Z","caller":"traceutil/trace.go:171","msg":"trace[966725828] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:953; }","duration":"564.238176ms","start":"2026-02-17T16:01:40.574429Z","end":"2026-02-17T16:01:41.138667Z","steps":["trace[966725828] 'count revisions from in-memory index tree' (duration: 561.658758ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:41.138838Z","caller":"traceutil/trace.go:171","msg":"trace[976865103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"424.900918ms","start":"2026-02-17T16:01:40.713932Z","end":"2026-02-17T16:01:41.138833Z","steps":["trace[976865103] 'range keys from in-memory index tree' (duration: 422.256348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:41.139469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:40.713923Z","time spent":"425.541772ms","remote":"127.0.0.1:53808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-17T16:01:41.138955Z","caller":"traceutil/trace.go:171","msg":"trace[753600368] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:953; }","duration":"231.10525ms","start":"2026-02-17T16:01:40.907842Z","end":"2026-02-17T16:01:41.138947Z","steps":["trace[753600368] 'count revisions from in-memory index tree' (duration: 228.466709ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:41.139044Z","caller":"traceutil/trace.go:171","msg":"trace[403312751] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:953; }","duration":"930.747839ms","start":"2026-02-17T16:01:40.208292Z","end":"2026-02-17T16:01:41.13904Z","steps":["trace[403312751] 'range keys from in-memory index tree' (duration: 928.123859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:41.139646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:40.208279Z","time spent":"931.361592ms","remote":"127.0.0.1:53654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-02-17T16:01:41.139148Z","caller":"traceutil/trace.go:171","msg":"trace[383131306] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:953; }","duration":"687.594877ms","start":"2026-02-17T16:01:40.451548Z","end":"2026-02-17T16:01:41.139143Z","steps":["trace[383131306] 'count revisions from in-memory index tree' (duration: 684.993488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:41.139901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:40.45153Z","time spent":"688.361075ms","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-02-17T16:01:41.139253Z","caller":"traceutil/trace.go:171","msg":"trace[1733911101] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:953; }","duration":"644.424846ms","start":"2026-02-17T16:01:40.494823Z","end":"2026-02-17T16:01:41.139248Z","steps":["trace[1733911101] 'count revisions from in-memory index tree' (duration: 641.766786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:41.140052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:40.494766Z","time spent":"645.279327ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:01:41.139359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:40.605932Z","time spent":"533.417819ms","remote":"127.0.0.1:53526","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-17T16:01:41.140169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:40.574413Z","time spent":"565.466885ms","remote":"127.0.0.1:54052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":103,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-17T16:01:41.363686Z","caller":"traceutil/trace.go:171","msg":"trace[776261331] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"211.263631ms","start":"2026-02-17T16:01:41.152401Z","end":"2026-02-17T16:01:41.363665Z","steps":["trace[776261331] 'process raft request' (duration: 211.138658ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:41.93081Z","caller":"traceutil/trace.go:171","msg":"trace[616252029] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"778.102889ms","start":"2026-02-17T16:01:41.152692Z","end":"2026-02-17T16:01:41.930795Z","steps":["trace[616252029] 'process raft request' (duration: 777.984667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:41.930929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:41.15268Z","time spent":"778.189672ms","remote":"127.0.0.1:53808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:01:41.931017Z","caller":"traceutil/trace.go:171","msg":"trace[1601530255] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:992; }","duration":"384.188819ms","start":"2026-02-17T16:01:41.546804Z","end":"2026-02-17T16:01:41.930993Z","steps":["trace[1601530255] 'read index received' (duration: 383.827941ms)","trace[1601530255] 'applied index is now lower than readState.Index' (duration: 359.878µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:01:41.931207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.408024ms","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-17T16:01:41.931227Z","caller":"traceutil/trace.go:171","msg":"trace[1571421825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:956; }","duration":"384.466445ms","start":"2026-02-17T16:01:41.546754Z","end":"2026-02-17T16:01:41.931221Z","steps":["trace[1571421825] 'agreement among raft nodes before linearized reading' (duration: 384.351823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:41.931244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:41.546735Z","time spent":"384.504076ms","remote":"127.0.0.1:53808","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-17T16:01:41.931244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.638693ms","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-17T16:01:41.931273Z","caller":"traceutil/trace.go:171","msg":"trace[604876881] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:956; }","duration":"162.717704ms","start":"2026-02-17T16:01:41.768548Z","end":"2026-02-17T16:01:41.931265Z","steps":["trace[604876881] 'agreement among raft nodes before linearized reading' (duration: 162.640892ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:41.931313Z","caller":"traceutil/trace.go:171","msg":"trace[1851096641] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"778.53703ms","start":"2026-02-17T16:01:41.152771Z","end":"2026-02-17T16:01:41.931308Z","steps":["trace[1851096641] 'process raft request' (duration: 777.997868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:41.93134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:41.152764Z","time spent":"778.56115ms","remote":"127.0.0.1:53808","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-17T16:01:41.931509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.889834ms","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-17T16:01:41.93153Z","caller":"traceutil/trace.go:171","msg":"trace[928290216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:956; }","duration":"153.933584ms","start":"2026-02-17T16:01:41.77759Z","end":"2026-02-17T16:01:41.931524Z","steps":["trace[928290216] 'agreement among raft nodes before linearized reading' (duration: 153.847152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:42.270114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.127402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:01:42.270429Z","caller":"traceutil/trace.go:171","msg":"trace[477733229] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"330.980971ms","start":"2026-02-17T16:01:41.939436Z","end":"2026-02-17T16:01:42.270417Z","steps":["trace[477733229] 'process raft request' (duration: 330.749605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:42.270486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:41.939429Z","time spent":"331.035842ms","remote":"127.0.0.1:53808","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-17T16:01:42.270744Z","caller":"traceutil/trace.go:171","msg":"trace[1229947114] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"332.248659ms","start":"2026-02-17T16:01:41.938487Z","end":"2026-02-17T16:01:42.270736Z","steps":["trace[1229947114] 'process raft request' (duration: 81.414951ms)","trace[1229947114] 'compare' (duration: 249.994059ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:01:42.270783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:41.938472Z","time spent":"332.2905ms","remote":"127.0.0.1:53808","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-17T16:01:43.591108Z","caller":"traceutil/trace.go:171","msg":"trace[2092874084] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"220.517575ms","start":"2026-02-17T16:01:43.370568Z","end":"2026-02-17T16:01:43.591086Z","steps":["trace[2092874084] 'process raft request' (duration: 220.405932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:44.097061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.648229ms","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-17T16:01:44.099323Z","caller":"traceutil/trace.go:171","msg":"trace[1278850147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:959; }","duration":"155.968131ms","start":"2026-02-17T16:01:43.943328Z","end":"2026-02-17T16:01:44.099296Z","steps":["trace[1278850147] 'range keys from in-memory index tree' (duration: 153.454415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:44.097205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.404991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T16:01:44.101643Z","caller":"traceutil/trace.go:171","msg":"trace[115416046] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"150.958409ms","start":"2026-02-17T16:01:43.948737Z","end":"2026-02-17T16:01:44.099695Z","steps":["trace[115416046] 'range keys from in-memory index tree' (duration: 148.249188ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:44.302536Z","caller":"traceutil/trace.go:171","msg":"trace[285402718] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"195.628054ms","start":"2026-02-17T16:01:44.10688Z","end":"2026-02-17T16:01:44.302508Z","steps":["trace[285402718] 'process raft request' (duration: 195.472661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:45.007749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.004885ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:01:45.007881Z","caller":"traceutil/trace.go:171","msg":"trace[1849058814] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:960; }","duration":"371.179899ms","start":"2026-02-17T16:01:44.636682Z","end":"2026-02-17T16:01:45.007862Z","steps":["trace[1849058814] 'range keys from in-memory index tree' (duration: 370.985725ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:45.00835Z","caller":"traceutil/trace.go:171","msg":"trace[1373466634] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"730.056785ms","start":"2026-02-17T16:01:44.278268Z","end":"2026-02-17T16:01:45.008325Z","steps":["trace[1373466634] 'read index received' (duration: 26.219644ms)","trace[1373466634] 'applied index is now lower than readState.Index' (duration: 703.836081ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:01:45.008463Z","caller":"traceutil/trace.go:171","msg":"trace[509285844] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"900.674012ms","start":"2026-02-17T16:01:44.107774Z","end":"2026-02-17T16:01:45.008448Z","steps":["trace[509285844] 'process raft request' (duration: 843.05857ms)","trace[509285844] 'compare' (duration: 56.835205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:01:45.008558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:44.107765Z","time spent":"900.724263ms","remote":"127.0.0.1:53808","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-17T16:01:45.00866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.31425ms","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-17T16:01:45.008713Z","caller":"traceutil/trace.go:171","msg":"trace[1130189107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:961; }","duration":"730.428473ms","start":"2026-02-17T16:01:44.278269Z","end":"2026-02-17T16:01:45.008698Z","steps":["trace[1130189107] 'agreement among raft nodes before linearized reading' (duration: 730.2819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:45.008763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:44.278238Z","time spent":"730.508515ms","remote":"127.0.0.1:53808","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-17T16:01:45.008944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.677049ms","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-17T16:01:45.008982Z","caller":"traceutil/trace.go:171","msg":"trace[387519966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"730.77683ms","start":"2026-02-17T16:01:44.278193Z","end":"2026-02-17T16:01:45.00897Z","steps":["trace[387519966] 'agreement among raft nodes before linearized reading' (duration: 730.649278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:45.008995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.159622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-17T16:01:45.009017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:44.278164Z","time spent":"730.845512ms","remote":"127.0.0.1:53808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T16:01:45.009026Z","caller":"traceutil/trace.go:171","msg":"trace[1023117276] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:961; }","duration":"657.218843ms","start":"2026-02-17T16:01:44.351796Z","end":"2026-02-17T16:01:45.009015Z","steps":["trace[1023117276] 'agreement among raft nodes before linearized reading' (duration: 657.125961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:01:45.009054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:01:44.351783Z","time spent":"657.264224ms","remote":"127.0.0.1:53542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"info","ts":"2026-02-17T16:01:45.249126Z","caller":"traceutil/trace.go:171","msg":"trace[969936467] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"146.100767ms","start":"2026-02-17T16:01:45.103004Z","end":"2026-02-17T16:01:45.249104Z","steps":["trace[969936467] 'process raft request' (duration: 146.059356ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:45.250587Z","caller":"traceutil/trace.go:171","msg":"trace[983346338] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"234.633272ms","start":"2026-02-17T16:01:45.015936Z","end":"2026-02-17T16:01:45.25057Z","steps":["trace[983346338] 'process raft request' (duration: 162.470349ms)","trace[983346338] 'compare' (duration: 70.303142ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:01:45.250636Z","caller":"traceutil/trace.go:171","msg":"trace[264503015] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"232.857112ms","start":"2026-02-17T16:01:45.017772Z","end":"2026-02-17T16:01:45.250629Z","steps":["trace[264503015] 'process raft request' (duration: 231.241577ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:46.192167Z","caller":"traceutil/trace.go:171","msg":"trace[2007002574] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"105.893821ms","start":"2026-02-17T16:01:46.086245Z","end":"2026-02-17T16:01:46.192139Z","steps":["trace[2007002574] 'read index received' (duration: 105.693286ms)","trace[2007002574] 'applied index is now lower than readState.Index' (duration: 198.824µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:01:46.192338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.062334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1780"} {"level":"info","ts":"2026-02-17T16:01:46.192384Z","caller":"traceutil/trace.go:171","msg":"trace[581629559] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:969; }","duration":"106.147297ms","start":"2026-02-17T16:01:46.086228Z","end":"2026-02-17T16:01:46.192375Z","steps":["trace[581629559] 'agreement among raft nodes before linearized reading' (duration: 106.008494ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:46.194008Z","caller":"traceutil/trace.go:171","msg":"trace[1946351060] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"122.699529ms","start":"2026-02-17T16:01:46.069636Z","end":"2026-02-17T16:01:46.192335Z","steps":["trace[1946351060] 'process raft request' (duration: 122.371732ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:01:56.523468Z","caller":"traceutil/trace.go:171","msg":"trace[1216937477] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"102.146073ms","start":"2026-02-17T16:01:56.421299Z","end":"2026-02-17T16:01:56.523445Z","steps":["trace[1216937477] 'process raft request' (duration: 101.858767ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:02:01.747267Z","caller":"traceutil/trace.go:171","msg":"trace[777237387] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"121.260441ms","start":"2026-02-17T16:02:01.625968Z","end":"2026-02-17T16:02:01.747229Z","steps":["trace[777237387] 'process raft request' (duration: 27.218297ms)","trace[777237387] 'compare' (duration: 93.888741ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:02:02.091891Z","caller":"traceutil/trace.go:171","msg":"trace[236517484] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"120.430293ms","start":"2026-02-17T16:02:01.971414Z","end":"2026-02-17T16:02:02.091844Z","steps":["trace[236517484] 'process raft request' (duration: 120.183588ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:02:04.697415Z","caller":"traceutil/trace.go:171","msg":"trace[76231418] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"110.429216ms","start":"2026-02-17T16:02:04.586965Z","end":"2026-02-17T16:02:04.697395Z","steps":["trace[76231418] 'process raft request' (duration: 109.833454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:06.042002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.149585ms","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-17T16:02:06.042648Z","caller":"traceutil/trace.go:171","msg":"trace[171080232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"196.280416ms","start":"2026-02-17T16:02:05.846352Z","end":"2026-02-17T16:02:06.042632Z","steps":["trace[171080232] 'range keys from in-memory index tree' (duration: 195.028782ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:02:06.042917Z","caller":"traceutil/trace.go:171","msg":"trace[1661810732] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"144.689526ms","start":"2026-02-17T16:02:05.898217Z","end":"2026-02-17T16:02:06.042906Z","steps":["trace[1661810732] 'read index received' (duration: 49.564613ms)","trace[1661810732] 'applied index is now lower than readState.Index' (duration: 95.124183ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:02:06.043157Z","caller":"traceutil/trace.go:171","msg":"trace[1942477706] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"182.93341ms","start":"2026-02-17T16:02:05.86021Z","end":"2026-02-17T16:02:06.043143Z","steps":["trace[1942477706] 'process raft request' (duration: 87.609093ms)","trace[1942477706] 'compare' (duration: 94.025342ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:02:06.043333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.108334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-17T16:02:06.043361Z","caller":"traceutil/trace.go:171","msg":"trace[1135264317] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:1024; }","duration":"145.162974ms","start":"2026-02-17T16:02:05.89819Z","end":"2026-02-17T16:02:06.043353Z","steps":["trace[1135264317] 'agreement among raft nodes before linearized reading' (duration: 145.080813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:06.043535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.796486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:1 size:612"} {"level":"info","ts":"2026-02-17T16:02:06.043561Z","caller":"traceutil/trace.go:171","msg":"trace[346618355] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:1; response_revision:1024; }","duration":"134.834186ms","start":"2026-02-17T16:02:05.908719Z","end":"2026-02-17T16:02:06.043553Z","steps":["trace[346618355] 'agreement among raft nodes before linearized reading' (duration: 134.786015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:06.046026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.441851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/\" range_end:\"/registry/daemonsets/kube-system0\" limit:500 ","response":"range_response_count:2 size:17215"} {"level":"info","ts":"2026-02-17T16:02:06.046325Z","caller":"traceutil/trace.go:171","msg":"trace[817429686] range","detail":"{range_begin:/registry/daemonsets/kube-system/; range_end:/registry/daemonsets/kube-system0; response_count:2; response_revision:1024; }","duration":"105.759808ms","start":"2026-02-17T16:02:05.940549Z","end":"2026-02-17T16:02:06.046309Z","steps":["trace[817429686] 'agreement among raft nodes before linearized reading' (duration: 105.399141ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:02:08.958615Z","caller":"traceutil/trace.go:171","msg":"trace[195029307] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"171.423503ms","start":"2026-02-17T16:02:08.787174Z","end":"2026-02-17T16:02:08.958597Z","steps":["trace[195029307] 'read index received' (duration: 171.27611ms)","trace[195029307] 'applied index is now lower than readState.Index' (duration: 146.523µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:02:08.958899Z","caller":"traceutil/trace.go:171","msg":"trace[1969799492] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"182.480501ms","start":"2026-02-17T16:02:08.776407Z","end":"2026-02-17T16:02:08.958888Z","steps":["trace[1969799492] 'process raft request' (duration: 182.089304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:08.959082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.892571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/kubeadm:bootstrap-signer-clusterinfo\" ","response":"range_response_count:1 size:496"} {"level":"info","ts":"2026-02-17T16:02:08.959113Z","caller":"traceutil/trace.go:171","msg":"trace[1577718245] range","detail":"{range_begin:/registry/rolebindings/kube-public/kubeadm:bootstrap-signer-clusterinfo; range_end:; response_count:1; response_revision:1032; }","duration":"171.952403ms","start":"2026-02-17T16:02:08.787152Z","end":"2026-02-17T16:02:08.959104Z","steps":["trace[1577718245] 'agreement among raft nodes before linearized reading' (duration: 171.886852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:08.959251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.882829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-02-17T16:02:08.959326Z","caller":"traceutil/trace.go:171","msg":"trace[213411392] range","detail":"{range_begin:/registry/rolebindings/kube-public/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1032; }","duration":"103.981561ms","start":"2026-02-17T16:02:08.855337Z","end":"2026-02-17T16:02:08.959318Z","steps":["trace[213411392] 'agreement among raft nodes before linearized reading' (duration: 103.889219ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:02:09.546506Z","caller":"traceutil/trace.go:171","msg":"trace[1490109081] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"110.44253ms","start":"2026-02-17T16:02:09.436047Z","end":"2026-02-17T16:02:09.54649Z","steps":["trace[1490109081] 'read index received' (duration: 110.294237ms)","trace[1490109081] 'applied index is now lower than readState.Index' (duration: 147.783µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:02:09.546717Z","caller":"traceutil/trace.go:171","msg":"trace[1146616700] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"119.882777ms","start":"2026-02-17T16:02:09.426828Z","end":"2026-02-17T16:02:09.546711Z","steps":["trace[1146616700] 'process raft request' (duration: 119.553081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:09.546865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.804727ms","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-17T16:02:09.546892Z","caller":"traceutil/trace.go:171","msg":"trace[2007849059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1033; }","duration":"110.859477ms","start":"2026-02-17T16:02:09.436028Z","end":"2026-02-17T16:02:09.546887Z","steps":["trace[2007849059] 'agreement among raft nodes before linearized reading' (duration: 110.776766ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:02:10.251335Z","caller":"traceutil/trace.go:171","msg":"trace[788282670] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"161.673902ms","start":"2026-02-17T16:02:10.089639Z","end":"2026-02-17T16:02:10.251313Z","steps":["trace[788282670] 'process raft request' (duration: 161.506949ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:02:10.251748Z","caller":"traceutil/trace.go:171","msg":"trace[1759988881] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1080; }","duration":"155.475247ms","start":"2026-02-17T16:02:10.096265Z","end":"2026-02-17T16:02:10.251741Z","steps":["trace[1759988881] 'read index received' (duration: 155.471197ms)","trace[1759988881] 'applied index is now lower than readState.Index' (duration: 3.26µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:02:10.252031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.644532ms","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-17T16:02:10.252058Z","caller":"traceutil/trace.go:171","msg":"trace[148581267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1036; }","duration":"139.693792ms","start":"2026-02-17T16:02:10.112358Z","end":"2026-02-17T16:02:10.252052Z","steps":["trace[148581267] 'agreement among raft nodes before linearized reading' (duration: 139.437068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:10.252399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.129169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6684"} {"level":"info","ts":"2026-02-17T16:02:10.252561Z","caller":"traceutil/trace.go:171","msg":"trace[1752291476] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:1036; }","duration":"156.305952ms","start":"2026-02-17T16:02:10.096247Z","end":"2026-02-17T16:02:10.252553Z","steps":["trace[1752291476] 'agreement among raft nodes before linearized reading' (duration: 156.102098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:10.252692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.10025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7036"} {"level":"info","ts":"2026-02-17T16:02:10.252735Z","caller":"traceutil/trace.go:171","msg":"trace[1372915393] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1036; }","duration":"103.362976ms","start":"2026-02-17T16:02:10.149363Z","end":"2026-02-17T16:02:10.252726Z","steps":["trace[1372915393] 'agreement among raft nodes before linearized reading' (duration: 103.095091ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:02:10.381495Z","caller":"traceutil/trace.go:171","msg":"trace[614485734] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"108.412149ms","start":"2026-02-17T16:02:10.27306Z","end":"2026-02-17T16:02:10.381472Z","steps":["trace[614485734] 'read index received' (duration: 107.872019ms)","trace[614485734] 'applied index is now lower than readState.Index' (duration: 539.06µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:02:10.381795Z","caller":"traceutil/trace.go:171","msg":"trace[1502627846] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"115.97073ms","start":"2026-02-17T16:02:10.265809Z","end":"2026-02-17T16:02:10.38178Z","steps":["trace[1502627846] 'process raft request' (duration: 115.168765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:10.382544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.46168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:71 size:52290"} {"level":"info","ts":"2026-02-17T16:02:10.382654Z","caller":"traceutil/trace.go:171","msg":"trace[1967126873] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:71; response_revision:1037; }","duration":"109.611202ms","start":"2026-02-17T16:02:10.273024Z","end":"2026-02-17T16:02:10.382636Z","steps":["trace[1967126873] 'agreement among raft nodes before linearized reading' (duration: 109.132923ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:02:12.588862Z","caller":"traceutil/trace.go:171","msg":"trace[1195305245] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"140.013608ms","start":"2026-02-17T16:02:12.44883Z","end":"2026-02-17T16:02:12.588844Z","steps":["trace[1195305245] 'read index received' (duration: 139.873695ms)","trace[1195305245] 'applied index is now lower than readState.Index' (duration: 139.163µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:02:12.58922Z","caller":"traceutil/trace.go:171","msg":"trace[1186629634] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"159.239011ms","start":"2026-02-17T16:02:12.429968Z","end":"2026-02-17T16:02:12.589207Z","steps":["trace[1186629634] 'process raft request' (duration: 158.747432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:12.589226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.685205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-scheduler\" ","response":"range_response_count:1 size:661"} {"level":"warn","ts":"2026-02-17T16:02:12.589594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.743841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:1 size:688"} {"level":"info","ts":"2026-02-17T16:02:12.589642Z","caller":"traceutil/trace.go:171","msg":"trace[1945831277] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:1043; }","duration":"140.842542ms","start":"2026-02-17T16:02:12.448788Z","end":"2026-02-17T16:02:12.589631Z","steps":["trace[1945831277] 'agreement among raft nodes before linearized reading' (duration: 140.73763ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:02:12.589362Z","caller":"traceutil/trace.go:171","msg":"trace[798304370] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:1; response_revision:1043; }","duration":"105.835978ms","start":"2026-02-17T16:02:12.483514Z","end":"2026-02-17T16:02:12.58935Z","steps":["trace[798304370] 'agreement among raft nodes before linearized reading' (duration: 105.646544ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:02:14.756405Z","caller":"traceutil/trace.go:171","msg":"trace[1041046557] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1093; }","duration":"141.142488ms","start":"2026-02-17T16:02:14.615244Z","end":"2026-02-17T16:02:14.756387Z","steps":["trace[1041046557] 'read index received' (duration: 140.997755ms)","trace[1041046557] 'applied index is now lower than readState.Index' (duration: 143.743µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:02:14.756734Z","caller":"traceutil/trace.go:171","msg":"trace[2035062005] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"141.785439ms","start":"2026-02-17T16:02:14.614918Z","end":"2026-02-17T16:02:14.756704Z","steps":["trace[2035062005] 'process raft request' (duration: 141.330081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:14.757288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.028464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:4 size:329940"} {"level":"info","ts":"2026-02-17T16:02:14.757523Z","caller":"traceutil/trace.go:171","msg":"trace[330428073] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1049; }","duration":"142.306709ms","start":"2026-02-17T16:02:14.615187Z","end":"2026-02-17T16:02:14.757494Z","steps":["trace[330428073] 'agreement among raft nodes before linearized reading' (duration: 141.727299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:16.244717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.147178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-17T16:02:16.244794Z","caller":"traceutil/trace.go:171","msg":"trace[302707906] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1052; }","duration":"101.258711ms","start":"2026-02-17T16:02:16.143519Z","end":"2026-02-17T16:02:16.244777Z","steps":["trace[302707906] 'range keys from in-memory index tree' (duration: 101.004846ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:02:16.548628Z","caller":"traceutil/trace.go:171","msg":"trace[837905061] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"191.082225ms","start":"2026-02-17T16:02:16.357531Z","end":"2026-02-17T16:02:16.548613Z","steps":["trace[837905061] 'read index received' (duration: 190.863101ms)","trace[837905061] 'applied index is now lower than readState.Index' (duration: 218.604µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:02:16.548734Z","caller":"traceutil/trace.go:171","msg":"trace[724507541] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"191.622235ms","start":"2026-02-17T16:02:16.357106Z","end":"2026-02-17T16:02:16.548728Z","steps":["trace[724507541] 'process raft request' (duration: 191.392791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:16.548927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.36344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-02-17T16:02:16.548967Z","caller":"traceutil/trace.go:171","msg":"trace[1157857953] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:1053; }","duration":"191.462583ms","start":"2026-02-17T16:02:16.357497Z","end":"2026-02-17T16:02:16.548959Z","steps":["trace[1157857953] 'agreement among raft nodes before linearized reading' (duration: 191.34471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:16.549155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.899099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-17T16:02:16.549185Z","caller":"traceutil/trace.go:171","msg":"trace[1908805722] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:1053; }","duration":"177.953119ms","start":"2026-02-17T16:02:16.371219Z","end":"2026-02-17T16:02:16.549173Z","steps":["trace[1908805722] 'agreement among raft nodes before linearized reading' (duration: 177.869848ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:02:21.291474Z","caller":"traceutil/trace.go:171","msg":"trace[171305686] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"117.26273ms","start":"2026-02-17T16:02:21.17419Z","end":"2026-02-17T16:02:21.291452Z","steps":["trace[171305686] 'read index received' (duration: 117.140128ms)","trace[171305686] 'applied index is now lower than readState.Index' (duration: 121.692µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:02:21.292221Z","caller":"traceutil/trace.go:171","msg":"trace[1051344055] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"126.46445ms","start":"2026-02-17T16:02:21.165358Z","end":"2026-02-17T16:02:21.291823Z","steps":["trace[1051344055] 'process raft request' (duration: 125.972992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:02:21.292544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.329549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bnccw\" ","response":"range_response_count:1 size:18058"} {"level":"info","ts":"2026-02-17T16:02:21.292702Z","caller":"traceutil/trace.go:171","msg":"trace[1056030466] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bnccw; range_end:; response_count:1; response_revision:1067; }","duration":"118.475601ms","start":"2026-02-17T16:02:21.174187Z","end":"2026-02-17T16:02:21.292663Z","steps":["trace[1056030466] 'agreement among raft nodes before linearized reading' (duration: 118.084554ms)"],"step_count":1}