{"level":"warn","ts":"2026-02-18T05:11:49.40918Z","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-18T05:11:49.409261Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209: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-18T05:11:49.410069Z","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-18T05:11:49.410087Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-18T05:11:49.410163Z","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-18T05:11:49.412124Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-02-18T05:11:49.416137Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209: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-18T05:11:49.424199Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.373846ms"} {"level":"info","ts":"2026-02-18T05:11:49.469311Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-02-18T05:11:49.469413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T05:11:49.469443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-02-18T05:11:49.469481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T05:11:49.469501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-02-18T05:11:49.469567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-02-18T05:11:49.55439Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T05:11:49.558881Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T05:11:49.563963Z","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-18T05:11:49.573416Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T05:11:49.574914Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T05:11:49.575393Z","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-18T05:11:49.575454Z","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-18T05:11:49.57548Z","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-18T05:11:49.586704Z","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-18T05:11:49.587246Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T05:11:49.587271Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T05:11:49.58733Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-18T05:11:49.587341Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-18T05:11:49.588254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-02-18T05:11:49.58903Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-18T05:11:50.170554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T05:11:50.170591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T05:11:50.170604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-02-18T05:11:50.170614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-02-18T05:11:50.17062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-18T05:11:50.170631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-02-18T05:11:50.170637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-18T05:11:50.223976Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T05:11:50.224094Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:11:50.224254Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:11:50.224173Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:11:50.224201Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T05:11:50.224653Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T05:11:50.227078Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-02-18T05:11:50.230545Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T05:11:50.261981Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:11:50.262151Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:11:50.262188Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:11:57.053693Z","caller":"traceutil/trace.go:171","msg":"trace[486844755] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"105.291103ms","start":"2026-02-18T05:11:56.948386Z","end":"2026-02-18T05:11:57.053677Z","steps":["trace[486844755] 'process raft request' (duration: 105.166512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:00.663669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.660945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T05:12:00.663778Z","caller":"traceutil/trace.go:171","msg":"trace[618683079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:394; }","duration":"191.818055ms","start":"2026-02-18T05:12:00.471942Z","end":"2026-02-18T05:12:00.66376Z","steps":["trace[618683079] 'range keys from in-memory index tree' (duration: 191.583143ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:01.751267Z","caller":"traceutil/trace.go:171","msg":"trace[231260311] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"175.414901ms","start":"2026-02-18T05:12:01.575834Z","end":"2026-02-18T05:12:01.751249Z","steps":["trace[231260311] 'process raft request' (duration: 175.034988ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:07.045988Z","caller":"traceutil/trace.go:171","msg":"trace[1594950368] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"189.274725ms","start":"2026-02-18T05:12:06.856697Z","end":"2026-02-18T05:12:07.045972Z","steps":["trace[1594950368] 'read index received' (duration: 189.126844ms)","trace[1594950368] 'applied index is now lower than readState.Index' (duration: 147.311µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:07.046088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.377816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:12:07.046108Z","caller":"traceutil/trace.go:171","msg":"trace[1400934834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:447; }","duration":"189.441356ms","start":"2026-02-18T05:12:06.856661Z","end":"2026-02-18T05:12:07.046102Z","steps":["trace[1400934834] 'agreement among raft nodes before linearized reading' (duration: 189.378916ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:07.046321Z","caller":"traceutil/trace.go:171","msg":"trace[560433081] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"344.665872ms","start":"2026-02-18T05:12:06.701648Z","end":"2026-02-18T05:12:07.046314Z","steps":["trace[560433081] 'process raft request' (duration: 344.220639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:07.046628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:06.70163Z","time spent":"344.707433ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:12:08.746179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.054456ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-18T05:12:12.308137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993129,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:12.808466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993129,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:13.212254Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.110861774s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:12:13.212962Z","caller":"traceutil/trace.go:171","msg":"trace[1416415069] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"2.111434628s","start":"2026-02-18T05:12:11.101279Z","end":"2026-02-18T05:12:13.212713Z","steps":["trace[1416415069] 'process raft request' (duration: 2.111245127s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:13.213114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:11.101263Z","time spent":"2.11177353s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:13.21495Z","caller":"traceutil/trace.go:171","msg":"trace[1959571632] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:470; }","duration":"1.407784304s","start":"2026-02-18T05:12:11.807154Z","end":"2026-02-18T05:12:13.214939Z","steps":["trace[1959571632] 'read index received' (duration: 1.405469289s)","trace[1959571632] 'applied index is now lower than readState.Index' (duration: 2.314495ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:13.215007Z","caller":"traceutil/trace.go:171","msg":"trace[1186814941] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"1.014075746s","start":"2026-02-18T05:12:12.200924Z","end":"2026-02-18T05:12:13.215Z","steps":["trace[1186814941] 'process raft request' (duration: 1.013950025s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:13.215054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:12.200901Z","time spent":"1.014120476s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:12:13.215094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356249373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:12:13.215137Z","caller":"traceutil/trace.go:171","msg":"trace[845844882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"1.356325804s","start":"2026-02-18T05:12:11.858797Z","end":"2026-02-18T05:12:13.215122Z","steps":["trace[845844882] 'agreement among raft nodes before linearized reading' (duration: 1.356265594s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:13.215172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:11.85878Z","time spent":"1.356383574s","remote":"127.0.0.1:56178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:12:13.215328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166116187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-18T05:12:13.215334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.408179026s","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-18T05:12:13.215356Z","caller":"traceutil/trace.go:171","msg":"trace[337241198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:457; }","duration":"1.408235066s","start":"2026-02-18T05:12:11.807115Z","end":"2026-02-18T05:12:13.21535Z","steps":["trace[337241198] 'agreement among raft nodes before linearized reading' (duration: 1.408200896s)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:13.215355Z","caller":"traceutil/trace.go:171","msg":"trace[79513802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:457; }","duration":"1.166180607s","start":"2026-02-18T05:12:12.049166Z","end":"2026-02-18T05:12:13.215347Z","steps":["trace[79513802] 'agreement among raft nodes before linearized reading' (duration: 1.166131227s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:13.215375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:11.807096Z","time spent":"1.408274968s","remote":"127.0.0.1:56474","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-18T05:12:13.215385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:12.049141Z","time spent":"1.166238428s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:12:13.215525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351936595s","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-18T05:12:13.215551Z","caller":"traceutil/trace.go:171","msg":"trace[1053491602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:457; }","duration":"1.351987995s","start":"2026-02-18T05:12:11.863555Z","end":"2026-02-18T05:12:13.215543Z","steps":["trace[1053491602] 'agreement among raft nodes before linearized reading' (duration: 1.351943364s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:13.215577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:11.863542Z","time spent":"1.352029205s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T05:12:15.342467Z","caller":"traceutil/trace.go:171","msg":"trace[327625864] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"122.231893ms","start":"2026-02-18T05:12:15.220213Z","end":"2026-02-18T05:12:15.342445Z","steps":["trace[327625864] 'process raft request' (duration: 122.087652ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:15.360538Z","caller":"traceutil/trace.go:171","msg":"trace[801140305] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:478; }","duration":"107.89436ms","start":"2026-02-18T05:12:15.252619Z","end":"2026-02-18T05:12:15.360514Z","steps":["trace[801140305] 'read index received' (duration: 107.88259ms)","trace[801140305] 'applied index is now lower than readState.Index' (duration: 9.63µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:15.407159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.528743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T05:12:15.407187Z","caller":"traceutil/trace.go:171","msg":"trace[1739580886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:462; }","duration":"154.589263ms","start":"2026-02-18T05:12:15.252589Z","end":"2026-02-18T05:12:15.407178Z","steps":["trace[1739580886] 'agreement among raft nodes before linearized reading' (duration: 108.017771ms)","trace[1739580886] 'range keys from in-memory index tree' (duration: 46.517942ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:15.407317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.263281ms","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-18T05:12:15.407331Z","caller":"traceutil/trace.go:171","msg":"trace[2134593967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:463; }","duration":"151.297601ms","start":"2026-02-18T05:12:15.256029Z","end":"2026-02-18T05:12:15.407327Z","steps":["trace[2134593967] 'agreement among raft nodes before linearized reading' (duration: 151.256321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:15.407501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.349205ms","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-18T05:12:15.407516Z","caller":"traceutil/trace.go:171","msg":"trace[946573050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:463; }","duration":"150.382485ms","start":"2026-02-18T05:12:15.257128Z","end":"2026-02-18T05:12:15.407511Z","steps":["trace[946573050] 'agreement among raft nodes before linearized reading' (duration: 150.358455ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:17.566822Z","caller":"traceutil/trace.go:171","msg":"trace[1808581676] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"117.298174ms","start":"2026-02-18T05:12:17.449414Z","end":"2026-02-18T05:12:17.566712Z","steps":["trace[1808581676] 'read index received' (duration: 117.233034ms)","trace[1808581676] 'applied index is now lower than readState.Index' (duration: 64.18µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:17.567004Z","caller":"traceutil/trace.go:171","msg":"trace[1653855518] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"199.18759ms","start":"2026-02-18T05:12:17.367805Z","end":"2026-02-18T05:12:17.566993Z","steps":["trace[1653855518] 'process raft request' (duration: 198.768087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:17.567361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.691138ms","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-18T05:12:17.567612Z","caller":"traceutil/trace.go:171","msg":"trace[1672400465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"111.939959ms","start":"2026-02-18T05:12:17.455621Z","end":"2026-02-18T05:12:17.567561Z","steps":["trace[1672400465] 'agreement among raft nodes before linearized reading' (duration: 111.670618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:17.567904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.483422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T05:12:17.568051Z","caller":"traceutil/trace.go:171","msg":"trace[2046327868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:467; }","duration":"118.647373ms","start":"2026-02-18T05:12:17.449392Z","end":"2026-02-18T05:12:17.568039Z","steps":["trace[2046327868] 'agreement among raft nodes before linearized reading' (duration: 118.479051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:17.568325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.902049ms","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-18T05:12:17.568491Z","caller":"traceutil/trace.go:171","msg":"trace[1543164627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"112.07739ms","start":"2026-02-18T05:12:17.456402Z","end":"2026-02-18T05:12:17.568479Z","steps":["trace[1543164627] 'agreement among raft nodes before linearized reading' (duration: 111.889089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:18.35973Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993160,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:18.860591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993160,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:19.360941Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993160,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:19.720353Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.147774707s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:12:19.720662Z","caller":"traceutil/trace.go:171","msg":"trace[2102246343] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"2.148134539s","start":"2026-02-18T05:12:17.572511Z","end":"2026-02-18T05:12:19.720646Z","steps":["trace[2102246343] 'process raft request' (duration: 2.148026499s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:19.720755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:17.572496Z","time spent":"2.14819337s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:19.727177Z","caller":"traceutil/trace.go:171","msg":"trace[2008025602] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"2.15295074s","start":"2026-02-18T05:12:17.574213Z","end":"2026-02-18T05:12:19.727164Z","steps":["trace[2008025602] 'process raft request' (duration: 2.152825779s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:19.727248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:17.574205Z","time spent":"2.1530031s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:19.727424Z","caller":"traceutil/trace.go:171","msg":"trace[884836124] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"2.152734408s","start":"2026-02-18T05:12:17.574683Z","end":"2026-02-18T05:12:19.727417Z","steps":["trace[884836124] 'process raft request' (duration: 2.152418446s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:19.727527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:17.574675Z","time spent":"2.152767179s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:19.762448Z","caller":"traceutil/trace.go:171","msg":"trace[251335083] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:484; }","duration":"1.903326443s","start":"2026-02-18T05:12:17.859102Z","end":"2026-02-18T05:12:19.762428Z","steps":["trace[251335083] 'read index received' (duration: 1.861931919s)","trace[251335083] 'applied index is now lower than readState.Index' (duration: 41.393594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:19.762556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.903445444s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T05:12:19.76257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.574817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T05:12:19.762579Z","caller":"traceutil/trace.go:171","msg":"trace[405136157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"1.903511815s","start":"2026-02-18T05:12:17.85906Z","end":"2026-02-18T05:12:19.762572Z","steps":["trace[405136157] 'agreement among raft nodes before linearized reading' (duration: 1.903467014s)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:19.762591Z","caller":"traceutil/trace.go:171","msg":"trace[774700238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:470; }","duration":"189.629469ms","start":"2026-02-18T05:12:19.572956Z","end":"2026-02-18T05:12:19.762585Z","steps":["trace[774700238] 'agreement among raft nodes before linearized reading' (duration: 189.576308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:19.762596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:17.859041Z","time spent":"1.903550745s","remote":"127.0.0.1:56178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:12:20.908268Z","caller":"traceutil/trace.go:171","msg":"trace[1728717952] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"207.556117ms","start":"2026-02-18T05:12:20.70069Z","end":"2026-02-18T05:12:20.908246Z","steps":["trace[1728717952] 'process raft request' (duration: 206.46924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:21.172511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.73967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:12:21.172998Z","caller":"traceutil/trace.go:171","msg":"trace[1754862362] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"225.794629ms","start":"2026-02-18T05:12:20.947184Z","end":"2026-02-18T05:12:21.172979Z","steps":["trace[1754862362] 'process raft request' (duration: 225.650978ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:21.173002Z","caller":"traceutil/trace.go:171","msg":"trace[2018625982] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"234.269693ms","start":"2026-02-18T05:12:20.938699Z","end":"2026-02-18T05:12:21.172969Z","steps":["trace[2018625982] 'process raft request' (duration: 104.01067ms)","trace[2018625982] 'compare' (duration: 129.408028ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:21.407456Z","caller":"traceutil/trace.go:171","msg":"trace[2048702913] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"154.642797ms","start":"2026-02-18T05:12:21.252788Z","end":"2026-02-18T05:12:21.407431Z","steps":["trace[2048702913] 'process raft request' (duration: 137.781111ms)","trace[2048702913] 'compare' (duration: 16.694875ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:23.670724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993233,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:24.171709Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993233,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:24.672744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993233,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:25.172916Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993233,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:25.673822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993233,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:25.860231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999971015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T05:12:25.860327Z","caller":"traceutil/trace.go:171","msg":"trace[601583930] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000127582s","start":"2026-02-18T05:12:23.86018Z","end":"2026-02-18T05:12:25.860307Z","steps":["trace[601583930] 'agreement among raft nodes before linearized reading' (duration: 1.999979736s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:25.860663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:23.860172Z","time spent":"2.000462404s","remote":"127.0.0.1:56178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:12:26.174566Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993233,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:26.674984Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993233,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:26.851192Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.963941435s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:12:26.851687Z","caller":"traceutil/trace.go:171","msg":"trace[1049626078] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"3.964468255s","start":"2026-02-18T05:12:22.887185Z","end":"2026-02-18T05:12:26.851653Z","steps":["trace[1049626078] 'process raft request' (duration: 3.96432343s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:26.851799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:22.887171Z","time spent":"3.964566259s","remote":"127.0.0.1:56280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":828,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:12:27.045905Z","caller":"traceutil/trace.go:171","msg":"trace[1485934706] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"190.125163ms","start":"2026-02-18T05:12:26.855759Z","end":"2026-02-18T05:12:27.045884Z","steps":["trace[1485934706] 'process raft request' (duration: 190.078212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.046185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.875569378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T05:12:27.046243Z","caller":"traceutil/trace.go:171","msg":"trace[386791204] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:511; }","duration":"3.875652411s","start":"2026-02-18T05:12:23.170568Z","end":"2026-02-18T05:12:27.04622Z","steps":["trace[386791204] 'agreement among raft nodes before linearized reading' (duration: 3.875545497s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.046281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:23.170553Z","time spent":"3.875720184s","remote":"127.0.0.1:56218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"info","ts":"2026-02-18T05:12:27.045981Z","caller":"traceutil/trace.go:171","msg":"trace[563335294] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.615108928s","start":"2026-02-18T05:12:25.430843Z","end":"2026-02-18T05:12:27.045952Z","steps":["trace[563335294] 'process raft request' (duration: 1.614937562s)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:27.046041Z","caller":"traceutil/trace.go:171","msg":"trace[829929604] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"3.455164502s","start":"2026-02-18T05:12:23.590866Z","end":"2026-02-18T05:12:27.04603Z","steps":["trace[829929604] 'process raft request' (duration: 3.395432425s)","trace[829929604] 'compare' (duration: 59.067862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:27.047782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:23.590846Z","time spent":"3.456867345s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:27.046095Z","caller":"traceutil/trace.go:171","msg":"trace[264599045] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:525; }","duration":"3.875488385s","start":"2026-02-18T05:12:23.170596Z","end":"2026-02-18T05:12:27.046084Z","steps":["trace[264599045] 'read index received' (duration: 3.681352342s)","trace[264599045] 'applied index is now lower than readState.Index' (duration: 194.134843ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:27.048093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.201073411s","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-18T05:12:27.048133Z","caller":"traceutil/trace.go:171","msg":"trace[122230053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"3.201147213s","start":"2026-02-18T05:12:23.846973Z","end":"2026-02-18T05:12:27.04812Z","steps":["trace[122230053] 'agreement among raft nodes before linearized reading' (duration: 3.201031679s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.048168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:23.846917Z","time spent":"3.201245137s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:12:27.048322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186915359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:12:27.048356Z","caller":"traceutil/trace.go:171","msg":"trace[152208348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"1.18694865s","start":"2026-02-18T05:12:25.861399Z","end":"2026-02-18T05:12:27.048348Z","steps":["trace[152208348] 'agreement among raft nodes before linearized reading' (duration: 1.186901498s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.048381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:25.861342Z","time spent":"1.187033653s","remote":"127.0.0.1:56186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:12:27.048525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.649860845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-18T05:12:27.048558Z","caller":"traceutil/trace.go:171","msg":"trace[449846646] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:511; }","duration":"2.649892766s","start":"2026-02-18T05:12:24.398657Z","end":"2026-02-18T05:12:27.04855Z","steps":["trace[449846646] 'agreement among raft nodes before linearized reading' (duration: 2.649833304s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.048588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:24.3986Z","time spent":"2.649977309s","remote":"127.0.0.1:56384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4041,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-18T05:12:27.048802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.18035535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T05:12:27.048866Z","caller":"traceutil/trace.go:171","msg":"trace[1490071625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"3.180470235s","start":"2026-02-18T05:12:23.868387Z","end":"2026-02-18T05:12:27.048857Z","steps":["trace[1490071625] 'agreement among raft nodes before linearized reading' (duration: 3.18034901s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.048893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:23.868377Z","time spent":"3.180510016s","remote":"127.0.0.1:56474","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-18T05:12:27.0491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.192517199s","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-18T05:12:27.049138Z","caller":"traceutil/trace.go:171","msg":"trace[53389493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"3.192575962s","start":"2026-02-18T05:12:23.856554Z","end":"2026-02-18T05:12:27.04913Z","steps":["trace[53389493] 'agreement among raft nodes before linearized reading' (duration: 3.192484858s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.049169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:23.856543Z","time spent":"3.192620013s","remote":"127.0.0.1:56474","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-18T05:12:27.049328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.193813059s","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-18T05:12:27.049372Z","caller":"traceutil/trace.go:171","msg":"trace[2095845034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"3.193896082s","start":"2026-02-18T05:12:23.855468Z","end":"2026-02-18T05:12:27.049364Z","steps":["trace[2095845034] 'agreement among raft nodes before linearized reading' (duration: 3.193810429s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.049402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:23.855446Z","time spent":"3.193950863s","remote":"127.0.0.1:56474","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-18T05:12:27.051215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:25.430817Z","time spent":"1.615879386s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:12:27.471832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.910009ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-02-18T05:12:27.472214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:27.050383Z","time spent":"421.827425ms","remote":"127.0.0.1:56218","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T05:12:27.528477Z","caller":"traceutil/trace.go:171","msg":"trace[882932478] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:528; }","duration":"467.813418ms","start":"2026-02-18T05:12:27.060608Z","end":"2026-02-18T05:12:27.528421Z","steps":["trace[882932478] 'read index received' (duration: 108.40225ms)","trace[882932478] 'applied index is now lower than readState.Index' (duration: 359.410338ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:27.528959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.258134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:12:27.52904Z","caller":"traceutil/trace.go:171","msg":"trace[908083534] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"468.470552ms","start":"2026-02-18T05:12:27.060545Z","end":"2026-02-18T05:12:27.529016Z","steps":["trace[908083534] 'process raft request' (duration: 466.892514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.529176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:27.06053Z","time spent":"468.575386ms","remote":"127.0.0.1:56384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:27.529186Z","caller":"traceutil/trace.go:171","msg":"trace[1274985732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"468.493142ms","start":"2026-02-18T05:12:27.06059Z","end":"2026-02-18T05:12:27.529083Z","steps":["trace[1274985732] 'agreement among raft nodes before linearized reading' (duration: 468.241503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.529447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:27.060585Z","time spent":"468.842356ms","remote":"127.0.0.1:56178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:12:27.531241Z","caller":"traceutil/trace.go:171","msg":"trace[268247797] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"470.447264ms","start":"2026-02-18T05:12:27.060784Z","end":"2026-02-18T05:12:27.531231Z","steps":["trace[268247797] 'process raft request' (duration: 467.430673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.531299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:27.06078Z","time spent":"470.491236ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:27.531395Z","caller":"traceutil/trace.go:171","msg":"trace[436186435] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"470.719154ms","start":"2026-02-18T05:12:27.060669Z","end":"2026-02-18T05:12:27.531388Z","steps":["trace[436186435] 'process raft request' (duration: 467.180914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.531436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:27.060666Z","time spent":"470.749726ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:27.531524Z","caller":"traceutil/trace.go:171","msg":"trace[1755838010] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"470.796608ms","start":"2026-02-18T05:12:27.060719Z","end":"2026-02-18T05:12:27.531515Z","steps":["trace[1755838010] 'process raft request' (duration: 467.209656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.531561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:27.060715Z","time spent":"470.827959ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:27.531776Z","caller":"traceutil/trace.go:171","msg":"trace[635026160] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"470.221936ms","start":"2026-02-18T05:12:27.061548Z","end":"2026-02-18T05:12:27.53177Z","steps":["trace[635026160] 'process raft request' (duration: 466.826731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.531818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:27.061542Z","time spent":"470.257757ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:27.529351Z","caller":"traceutil/trace.go:171","msg":"trace[2029602] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"468.705051ms","start":"2026-02-18T05:12:27.060635Z","end":"2026-02-18T05:12:27.52934Z","steps":["trace[2029602] 'process raft request' (duration: 467.04557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:27.531957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:27.060631Z","time spent":"471.308936ms","remote":"127.0.0.1:56280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:12:27.686016Z","caller":"traceutil/trace.go:171","msg":"trace[1004716903] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"148.562178ms","start":"2026-02-18T05:12:27.537438Z","end":"2026-02-18T05:12:27.686001Z","steps":["trace[1004716903] 'read index received' (duration: 75.598702ms)","trace[1004716903] 'applied index is now lower than readState.Index' (duration: 72.962856ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:27.686108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.657741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:12:27.686131Z","caller":"traceutil/trace.go:171","msg":"trace[502428612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"148.715343ms","start":"2026-02-18T05:12:27.537411Z","end":"2026-02-18T05:12:27.686126Z","steps":["trace[502428612] 'agreement among raft nodes before linearized reading' (duration: 148.651551ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:27.68624Z","caller":"traceutil/trace.go:171","msg":"trace[1062052021] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"212.71938ms","start":"2026-02-18T05:12:27.473491Z","end":"2026-02-18T05:12:27.68621Z","steps":["trace[1062052021] 'process raft request' (duration: 139.523615ms)","trace[1062052021] 'compare' (duration: 72.823071ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:31.700444Z","caller":"traceutil/trace.go:171","msg":"trace[1496983294] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"122.260444ms","start":"2026-02-18T05:12:31.578166Z","end":"2026-02-18T05:12:31.700426Z","steps":["trace[1496983294] 'process raft request' (duration: 122.130359ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:31.713609Z","caller":"traceutil/trace.go:171","msg":"trace[709280822] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"134.247235ms","start":"2026-02-18T05:12:31.579329Z","end":"2026-02-18T05:12:31.713576Z","steps":["trace[709280822] 'process raft request' (duration: 134.227194ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:31.714868Z","caller":"traceutil/trace.go:171","msg":"trace[217408216] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"136.423502ms","start":"2026-02-18T05:12:31.578435Z","end":"2026-02-18T05:12:31.714858Z","steps":["trace[217408216] 'process raft request' (duration: 135.013292ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:31.71503Z","caller":"traceutil/trace.go:171","msg":"trace[1608100196] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"136.552076ms","start":"2026-02-18T05:12:31.578471Z","end":"2026-02-18T05:12:31.715023Z","steps":["trace[1608100196] 'process raft request' (duration: 135.046533ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:31.715103Z","caller":"traceutil/trace.go:171","msg":"trace[715826707] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"136.567997ms","start":"2026-02-18T05:12:31.578517Z","end":"2026-02-18T05:12:31.715085Z","steps":["trace[715826707] 'process raft request' (duration: 135.021903ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:32.045541Z","caller":"traceutil/trace.go:171","msg":"trace[1994642993] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"130.601211ms","start":"2026-02-18T05:12:31.914874Z","end":"2026-02-18T05:12:32.045475Z","steps":["trace[1994642993] 'process raft request' (duration: 130.437746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:32.753705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993333,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:33.253975Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993333,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:33.331517Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.08477989s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T05:12:34.039003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.502020732s","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-18T05:12:34.03922Z","caller":"traceutil/trace.go:171","msg":"trace[1444377302] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:569; }","duration":"1.50224655s","start":"2026-02-18T05:12:32.536954Z","end":"2026-02-18T05:12:34.0392Z","steps":["trace[1444377302] 'range keys from in-memory index tree' (duration: 1.502006281s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.040923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.652584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:12:34.04119Z","caller":"traceutil/trace.go:171","msg":"trace[820891983] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.794533705s","start":"2026-02-18T05:12:32.246637Z","end":"2026-02-18T05:12:34.041171Z","steps":["trace[820891983] 'process raft request' (duration: 1.794492194s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.041301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:32.24662Z","time spent":"1.79464773s","remote":"127.0.0.1:56306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1745,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:12:34.041507Z","caller":"traceutil/trace.go:171","msg":"trace[719852296] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.794846876s","start":"2026-02-18T05:12:32.24664Z","end":"2026-02-18T05:12:34.041487Z","steps":["trace[719852296] 'process raft request' (duration: 1.794396881s)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:34.041537Z","caller":"traceutil/trace.go:171","msg":"trace[1640154025] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"1.800329896s","start":"2026-02-18T05:12:32.24119Z","end":"2026-02-18T05:12:34.041519Z","steps":["trace[1640154025] 'process raft request' (duration: 1.090523459s)","trace[1640154025] 'compare' (duration: 707.139246ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:34.041571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:32.246631Z","time spent":"1.794900268s","remote":"127.0.0.1:56304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12712,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:12:34.04166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:32.241184Z","time spent":"1.800393389s","remote":"127.0.0.1:56408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:12:34.041972Z","caller":"traceutil/trace.go:171","msg":"trace[1753523837] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.78921694s","start":"2026-02-18T05:12:32.252745Z","end":"2026-02-18T05:12:34.041962Z","steps":["trace[1753523837] 'process raft request' (duration: 1.789080215s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.042857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:32.252735Z","time spent":"1.790077449s","remote":"127.0.0.1:56646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3328,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:34.042177Z","caller":"traceutil/trace.go:171","msg":"trace[1246042681] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:589; }","duration":"1.789422848s","start":"2026-02-18T05:12:32.252733Z","end":"2026-02-18T05:12:34.042156Z","steps":["trace[1246042681] 'read index received' (duration: 1.079064882s)","trace[1246042681] 'applied index is now lower than readState.Index' (duration: 710.356686ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:34.042298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.789549382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:12:34.043224Z","caller":"traceutil/trace.go:171","msg":"trace[1185563512] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:575; }","duration":"1.790496903s","start":"2026-02-18T05:12:32.25271Z","end":"2026-02-18T05:12:34.043207Z","steps":["trace[1185563512] 'agreement among raft nodes before linearized reading' (duration: 1.78952212s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.043267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:32.252699Z","time spent":"1.790555846s","remote":"127.0.0.1:56354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" "} {"level":"warn","ts":"2026-02-18T05:12:34.043581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.531258164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:12:34.043639Z","caller":"traceutil/trace.go:171","msg":"trace[1206409093] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:575; }","duration":"1.531367288s","start":"2026-02-18T05:12:32.512262Z","end":"2026-02-18T05:12:34.043629Z","steps":["trace[1206409093] 'agreement among raft nodes before linearized reading' (duration: 1.53113876s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.043675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:32.512244Z","time spent":"1.531419899s","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:12:34.04369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.72675ms","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-18T05:12:34.043735Z","caller":"traceutil/trace.go:171","msg":"trace[1610582251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"315.786902ms","start":"2026-02-18T05:12:33.727936Z","end":"2026-02-18T05:12:34.043723Z","steps":["trace[1610582251] 'agreement among raft nodes before linearized reading' (duration: 315.632427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.043767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:33.727929Z","time spent":"315.831633ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:12:34.043888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.36367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T05:12:34.04392Z","caller":"traceutil/trace.go:171","msg":"trace[963906862] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"318.413992ms","start":"2026-02-18T05:12:33.725495Z","end":"2026-02-18T05:12:34.043909Z","steps":["trace[963906862] 'agreement among raft nodes before linearized reading' (duration: 318.297038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.043947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:33.725485Z","time spent":"318.454793ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T05:12:34.042419Z","caller":"traceutil/trace.go:171","msg":"trace[1392339593] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"1.789642164s","start":"2026-02-18T05:12:32.252765Z","end":"2026-02-18T05:12:34.042407Z","steps":["trace[1392339593] 'process raft request' (duration: 1.789162908s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.04406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:32.252761Z","time spent":"1.79126771s","remote":"127.0.0.1:56280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":761,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:12:34.044141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.7544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-18T05:12:34.044172Z","caller":"traceutil/trace.go:171","msg":"trace[1679219724] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:575; }","duration":"331.836703ms","start":"2026-02-18T05:12:33.712326Z","end":"2026-02-18T05:12:34.044163Z","steps":["trace[1679219724] 'agreement among raft nodes before linearized reading' (duration: 331.725439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.044198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:33.712306Z","time spent":"331.885405ms","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:12:34.044261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.312316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-18T05:12:34.044295Z","caller":"traceutil/trace.go:171","msg":"trace[1724599900] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:575; }","duration":"130.387289ms","start":"2026-02-18T05:12:33.913896Z","end":"2026-02-18T05:12:34.044283Z","steps":["trace[1724599900] 'agreement among raft nodes before linearized reading' (duration: 130.325857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.044343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.120253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-18T05:12:34.044372Z","caller":"traceutil/trace.go:171","msg":"trace[2003869677] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:575; }","duration":"531.183475ms","start":"2026-02-18T05:12:33.51318Z","end":"2026-02-18T05:12:34.044363Z","steps":["trace[2003869677] 'agreement among raft nodes before linearized reading' (duration: 531.126063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.044398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:33.513162Z","time spent":"531.229557ms","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13251,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:12:34.044476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.837826ms","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-18T05:12:34.044504Z","caller":"traceutil/trace.go:171","msg":"trace[108868222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"304.881658ms","start":"2026-02-18T05:12:33.739614Z","end":"2026-02-18T05:12:34.044496Z","steps":["trace[108868222] 'agreement among raft nodes before linearized reading' (duration: 304.804906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.044529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:33.739606Z","time spent":"304.916749ms","remote":"127.0.0.1:56474","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-18T05:12:34.045047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.614403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-18T05:12:34.045086Z","caller":"traceutil/trace.go:171","msg":"trace[862684200] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:575; }","duration":"731.701987ms","start":"2026-02-18T05:12:33.313373Z","end":"2026-02-18T05:12:34.045075Z","steps":["trace[862684200] 'agreement among raft nodes before linearized reading' (duration: 731.134418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.045117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:33.313351Z","time spent":"731.758259ms","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:12:34.04573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.412005ms","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-18T05:12:34.045751Z","caller":"traceutil/trace.go:171","msg":"trace[195892511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:575; }","duration":"874.444946ms","start":"2026-02-18T05:12:33.171301Z","end":"2026-02-18T05:12:34.045746Z","steps":["trace[195892511] 'agreement among raft nodes before linearized reading' (duration: 874.382874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.045765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:33.171298Z","time spent":"874.463786ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:12:34.04583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.592641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T05:12:34.045843Z","caller":"traceutil/trace.go:171","msg":"trace[695395075] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:575; }","duration":"874.633003ms","start":"2026-02-18T05:12:33.171206Z","end":"2026-02-18T05:12:34.045839Z","steps":["trace[695395075] 'agreement among raft nodes before linearized reading' (duration: 874.606092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.045855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:33.171191Z","time spent":"874.660523ms","remote":"127.0.0.1:56218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"warn","ts":"2026-02-18T05:12:34.045949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.774122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-18T05:12:34.045963Z","caller":"traceutil/trace.go:171","msg":"trace[1249853136] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:575; }","duration":"933.818684ms","start":"2026-02-18T05:12:33.112139Z","end":"2026-02-18T05:12:34.045958Z","steps":["trace[1249853136] 'agreement among raft nodes before linearized reading' (duration: 933.779532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.045978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:33.112124Z","time spent":"933.849424ms","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5608,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:12:34.04605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133498622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:12:34.046075Z","caller":"traceutil/trace.go:171","msg":"trace[1293122564] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:575; }","duration":"1.133539153s","start":"2026-02-18T05:12:32.912525Z","end":"2026-02-18T05:12:34.046064Z","steps":["trace[1293122564] 'agreement among raft nodes before linearized reading' (duration: 1.133512362s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.046087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:32.912513Z","time spent":"1.133571624s","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:12:34.046192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185738485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:12:34.046206Z","caller":"traceutil/trace.go:171","msg":"trace[472142248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"1.185788107s","start":"2026-02-18T05:12:32.860413Z","end":"2026-02-18T05:12:34.046201Z","steps":["trace[472142248] 'agreement among raft nodes before linearized reading' (duration: 1.185754446s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.046219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:32.860399Z","time spent":"1.185816539s","remote":"127.0.0.1:56178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:12:34.046415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.334236621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:12:34.046437Z","caller":"traceutil/trace.go:171","msg":"trace[101971762] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:575; }","duration":"1.334293683s","start":"2026-02-18T05:12:32.712138Z","end":"2026-02-18T05:12:34.046432Z","steps":["trace[101971762] 'agreement among raft nodes before linearized reading' (duration: 1.33421706s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.046453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:32.712118Z","time spent":"1.334331804s","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:12:34.047597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.202178ms","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-18T05:12:34.047619Z","caller":"traceutil/trace.go:171","msg":"trace[1730536530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"312.240279ms","start":"2026-02-18T05:12:33.735374Z","end":"2026-02-18T05:12:34.047614Z","steps":["trace[1730536530] 'agreement among raft nodes before linearized reading' (duration: 312.188427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.047633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:33.735367Z","time spent":"312.262829ms","remote":"127.0.0.1:56474","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":"info","ts":"2026-02-18T05:12:34.042525Z","caller":"traceutil/trace.go:171","msg":"trace[1487876551] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"1.72843996s","start":"2026-02-18T05:12:32.314077Z","end":"2026-02-18T05:12:34.042517Z","steps":["trace[1487876551] 'process raft request' (duration: 1.728018556s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:34.047803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:32.314059Z","time spent":"1.733728559s","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:12:34.691617Z","caller":"traceutil/trace.go:171","msg":"trace[2134771195] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"103.811392ms","start":"2026-02-18T05:12:34.587788Z","end":"2026-02-18T05:12:34.691599Z","steps":["trace[2134771195] 'process raft request' (duration: 42.818633ms)","trace[2134771195] 'compare' (duration: 60.876595ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:35.280604Z","caller":"traceutil/trace.go:171","msg":"trace[28799305] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"122.091566ms","start":"2026-02-18T05:12:35.158497Z","end":"2026-02-18T05:12:35.280588Z","steps":["trace[28799305] 'process raft request' (duration: 121.846988ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:35.492375Z","caller":"traceutil/trace.go:171","msg":"trace[424405562] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"162.514031ms","start":"2026-02-18T05:12:35.329845Z","end":"2026-02-18T05:12:35.492359Z","steps":["trace[424405562] 'read index received' (duration: 72.13098ms)","trace[424405562] 'applied index is now lower than readState.Index' (duration: 90.382281ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:35.492538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.675117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-18T05:12:35.492557Z","caller":"traceutil/trace.go:171","msg":"trace[413295328] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:597; }","duration":"162.729559ms","start":"2026-02-18T05:12:35.329821Z","end":"2026-02-18T05:12:35.492551Z","steps":["trace[413295328] 'agreement among raft nodes before linearized reading' (duration: 162.598605ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:35.492658Z","caller":"traceutil/trace.go:171","msg":"trace[1344273411] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"316.022141ms","start":"2026-02-18T05:12:35.17663Z","end":"2026-02-18T05:12:35.492652Z","steps":["trace[1344273411] 'process raft request' (duration: 225.337659ms)","trace[1344273411] 'compare' (duration: 90.217787ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:35.492698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:35.176609Z","time spent":"316.061382ms","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:12:35.499138Z","caller":"traceutil/trace.go:171","msg":"trace[425209615] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"120.527093ms","start":"2026-02-18T05:12:35.378586Z","end":"2026-02-18T05:12:35.499113Z","steps":["trace[425209615] 'process raft request' (duration: 116.175187ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:36.299605Z","caller":"traceutil/trace.go:171","msg":"trace[1553931639] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"137.184059ms","start":"2026-02-18T05:12:36.162413Z","end":"2026-02-18T05:12:36.299598Z","steps":["trace[1553931639] 'process raft request' (duration: 126.452873ms)","trace[1553931639] 'compare' (duration: 10.468948ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:36.299451Z","caller":"traceutil/trace.go:171","msg":"trace[104586570] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"131.012965ms","start":"2026-02-18T05:12:36.168422Z","end":"2026-02-18T05:12:36.299435Z","steps":["trace[104586570] 'read index received' (duration: 120.475686ms)","trace[104586570] 'applied index is now lower than readState.Index' (duration: 10.536569ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:36.299991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.218833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-18T05:12:36.300013Z","caller":"traceutil/trace.go:171","msg":"trace[1279645675] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:604; }","duration":"106.266915ms","start":"2026-02-18T05:12:36.193738Z","end":"2026-02-18T05:12:36.300005Z","steps":["trace[1279645675] 'agreement among raft nodes before linearized reading' (duration: 106.092219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:36.300662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.235235ms","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-18T05:12:36.305697Z","caller":"traceutil/trace.go:171","msg":"trace[496418564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:604; }","duration":"137.286794ms","start":"2026-02-18T05:12:36.168404Z","end":"2026-02-18T05:12:36.30569Z","steps":["trace[496418564] 'agreement among raft nodes before linearized reading' (duration: 131.393728ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:38.297655Z","caller":"traceutil/trace.go:171","msg":"trace[764615553] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"131.307097ms","start":"2026-02-18T05:12:38.166325Z","end":"2026-02-18T05:12:38.297632Z","steps":["trace[764615553] 'read index received' (duration: 130.983047ms)","trace[764615553] 'applied index is now lower than readState.Index' (duration: 322.97µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:38.297854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.504734ms","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-18T05:12:38.297896Z","caller":"traceutil/trace.go:171","msg":"trace[1222667185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"131.585417ms","start":"2026-02-18T05:12:38.166298Z","end":"2026-02-18T05:12:38.297884Z","steps":["trace[1222667185] 'agreement among raft nodes before linearized reading' (duration: 131.419471ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:38.298204Z","caller":"traceutil/trace.go:171","msg":"trace[1105709107] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"176.207924ms","start":"2026-02-18T05:12:38.121986Z","end":"2026-02-18T05:12:38.298194Z","steps":["trace[1105709107] 'process raft request' (duration: 175.383777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:42.760701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993458,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:12:42.778768Z","caller":"traceutil/trace.go:171","msg":"trace[305358500] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"519.656114ms","start":"2026-02-18T05:12:42.259095Z","end":"2026-02-18T05:12:42.778751Z","steps":["trace[305358500] 'process raft request' (duration: 519.52975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:42.778871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:42.259082Z","time spent":"519.733616ms","remote":"127.0.0.1:56398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:42.806649Z","caller":"traceutil/trace.go:171","msg":"trace[224046019] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"546.540118ms","start":"2026-02-18T05:12:42.260087Z","end":"2026-02-18T05:12:42.806627Z","steps":["trace[224046019] 'read index received' (duration: 518.56755ms)","trace[224046019] 'applied index is now lower than readState.Index' (duration: 27.971568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:42.806806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.698543ms","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-18T05:12:42.806834Z","caller":"traceutil/trace.go:171","msg":"trace[2087630991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"546.756745ms","start":"2026-02-18T05:12:42.260069Z","end":"2026-02-18T05:12:42.806826Z","steps":["trace[2087630991] 'agreement among raft nodes before linearized reading' (duration: 546.627311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:42.806854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:42.260063Z","time spent":"546.786176ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:12:42.806863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.554532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T05:12:42.806882Z","caller":"traceutil/trace.go:171","msg":"trace[1209903367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:622; }","duration":"470.595474ms","start":"2026-02-18T05:12:42.336281Z","end":"2026-02-18T05:12:42.806876Z","steps":["trace[1209903367] 'agreement among raft nodes before linearized reading' (duration: 470.547402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:42.806901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:42.336269Z","time spent":"470.628164ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:12:42.80698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.721496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T05:12:42.806996Z","caller":"traceutil/trace.go:171","msg":"trace[730986249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"488.755226ms","start":"2026-02-18T05:12:42.318235Z","end":"2026-02-18T05:12:42.80699Z","steps":["trace[730986249] 'agreement among raft nodes before linearized reading' (duration: 488.710815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:42.806806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.044871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-18T05:12:42.807012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:42.318226Z","time spent":"488.783018ms","remote":"127.0.0.1:56474","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-18T05:12:42.807116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.765988ms","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-18T05:12:42.807015Z","caller":"traceutil/trace.go:171","msg":"trace[1962559512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:622; }","duration":"494.306519ms","start":"2026-02-18T05:12:42.312703Z","end":"2026-02-18T05:12:42.80701Z","steps":["trace[1962559512] 'agreement among raft nodes before linearized reading' (duration: 494.02404ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:42.807129Z","caller":"traceutil/trace.go:171","msg":"trace[420734039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"489.801179ms","start":"2026-02-18T05:12:42.317324Z","end":"2026-02-18T05:12:42.807125Z","steps":["trace[420734039] 'agreement among raft nodes before linearized reading' (duration: 489.759188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:42.807142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:42.317313Z","time spent":"489.82572ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:12:42.80714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:42.312684Z","time spent":"494.450713ms","remote":"127.0.0.1:56474","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-18T05:12:43.67241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993462,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:44.172736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993462,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:44.597483Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.752973557s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T05:12:45.726921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129175406s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:12:45.727114Z","caller":"traceutil/trace.go:171","msg":"trace[671065957] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:645; }","duration":"2.555734009s","start":"2026-02-18T05:12:43.171369Z","end":"2026-02-18T05:12:45.727103Z","steps":["trace[671065957] 'read index received' (duration: 1.426285025s)","trace[671065957] 'applied index is now lower than readState.Index' (duration: 1.129448144s)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:45.727278Z","caller":"traceutil/trace.go:171","msg":"trace[1438664884] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"2.915504747s","start":"2026-02-18T05:12:42.811765Z","end":"2026-02-18T05:12:45.72727Z","steps":["trace[1438664884] 'process raft request' (duration: 1.785900148s)","trace[1438664884] 'compare' (duration: 1.128488925s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:45.727321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:42.811754Z","time spent":"2.915545348s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:45.727437Z","caller":"traceutil/trace.go:171","msg":"trace[1236959689] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"2.91558828s","start":"2026-02-18T05:12:42.811842Z","end":"2026-02-18T05:12:45.72743Z","steps":["trace[1236959689] 'process raft request' (duration: 2.915152927s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:45.727469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:42.811836Z","time spent":"2.91561509s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:45.727548Z","caller":"traceutil/trace.go:171","msg":"trace[1025230213] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"2.913922358s","start":"2026-02-18T05:12:42.813617Z","end":"2026-02-18T05:12:45.72754Z","steps":["trace[1025230213] 'process raft request' (duration: 2.913436493s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:45.727582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:42.813612Z","time spent":"2.913950619s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:45.727659Z","caller":"traceutil/trace.go:171","msg":"trace[705640940] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"2.913141863s","start":"2026-02-18T05:12:42.814511Z","end":"2026-02-18T05:12:45.727652Z","steps":["trace[705640940] 'process raft request' (duration: 2.912565486s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:45.727691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:42.814501Z","time spent":"2.913171834s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:12:45.727827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.556461691s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T05:12:45.727846Z","caller":"traceutil/trace.go:171","msg":"trace[598096024] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:627; }","duration":"2.556514443s","start":"2026-02-18T05:12:43.171325Z","end":"2026-02-18T05:12:45.727839Z","steps":["trace[598096024] 'agreement among raft nodes before linearized reading' (duration: 2.55643342s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:45.727865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:43.171312Z","time spent":"2.556548504s","remote":"127.0.0.1:56218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"warn","ts":"2026-02-18T05:12:45.727972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.871970907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:12:45.727989Z","caller":"traceutil/trace.go:171","msg":"trace[526237178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"1.872010568s","start":"2026-02-18T05:12:43.855973Z","end":"2026-02-18T05:12:45.727983Z","steps":["trace[526237178] 'agreement among raft nodes before linearized reading' (duration: 1.871979977s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:45.728005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:43.85596Z","time spent":"1.872041169s","remote":"127.0.0.1:56178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:12:45.728238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.375637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T05:12:45.72826Z","caller":"traceutil/trace.go:171","msg":"trace[372452830] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"879.418188ms","start":"2026-02-18T05:12:44.848836Z","end":"2026-02-18T05:12:45.728254Z","steps":["trace[372452830] 'agreement among raft nodes before linearized reading' (duration: 879.353826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:45.728278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:44.84882Z","time spent":"879.453019ms","remote":"127.0.0.1:56474","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-18T05:12:46.236055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993469,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:46.73716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993469,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:47.237863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993469,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:47.731005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.995902907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T05:12:47.731076Z","caller":"traceutil/trace.go:171","msg":"trace[2075403925] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.995984429s","start":"2026-02-18T05:12:45.73508Z","end":"2026-02-18T05:12:47.731064Z","steps":["trace[2075403925] 'agreement among raft nodes before linearized reading' (duration: 1.995900016s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:47.73111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:45.735056Z","time spent":"1.99604196s","remote":"127.0.0.1:56186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 05:12:47 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T05:12:47.737965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993469,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:48.238909Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993469,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:48.739791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993469,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:12:48.771521Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.036984694s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T05:12:49.106896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.146833ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-18T05:12:49.107074Z","caller":"traceutil/trace.go:171","msg":"trace[1430840955] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:649; }","duration":"3.371977603s","start":"2026-02-18T05:12:45.735084Z","end":"2026-02-18T05:12:49.107061Z","steps":["trace[1430840955] 'read index received' (duration: 3.036676144s)","trace[1430840955] 'applied index is now lower than readState.Index' (duration: 335.300879ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:49.107132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:45.734427Z","time spent":"3.372701865s","remote":"127.0.0.1:56218","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T05:12:49.107288Z","caller":"traceutil/trace.go:171","msg":"trace[901187294] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"3.372263241s","start":"2026-02-18T05:12:45.735018Z","end":"2026-02-18T05:12:49.107281Z","steps":["trace[901187294] 'process raft request' (duration: 3.371939842s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:49.107339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:45.735005Z","time spent":"3.372301043s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:49.107404Z","caller":"traceutil/trace.go:171","msg":"trace[686820671] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.697616198s","start":"2026-02-18T05:12:47.409783Z","end":"2026-02-18T05:12:49.107399Z","steps":["trace[686820671] 'process raft request' (duration: 1.697235377s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:49.107434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:47.409755Z","time spent":"1.69766602s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:49.107494Z","caller":"traceutil/trace.go:171","msg":"trace[611975207] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.689471869s","start":"2026-02-18T05:12:47.418018Z","end":"2026-02-18T05:12:49.10749Z","steps":["trace[611975207] 'process raft request' (duration: 1.689021306s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:49.107524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:47.418008Z","time spent":"1.6895008s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:12:49.107602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370499923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18953f3a104b010d\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-18T05:12:49.10762Z","caller":"traceutil/trace.go:171","msg":"trace[653978840] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18953f3a104b010d; range_end:; response_count:1; response_revision:630; }","duration":"1.370540444s","start":"2026-02-18T05:12:47.737075Z","end":"2026-02-18T05:12:49.107616Z","steps":["trace[653978840] 'agreement among raft nodes before linearized reading' (duration: 1.370501532s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:49.107634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:47.737063Z","time spent":"1.370568725s","remote":"127.0.0.1:56280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18953f3a104b010d\" "} {"level":"warn","ts":"2026-02-18T05:12:49.10779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369183694s","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-18T05:12:49.10781Z","caller":"traceutil/trace.go:171","msg":"trace[386057332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"1.369211645s","start":"2026-02-18T05:12:47.738594Z","end":"2026-02-18T05:12:49.107806Z","steps":["trace[386057332] 'agreement among raft nodes before linearized reading' (duration: 1.369150523s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:49.107823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:47.73859Z","time spent":"1.369230295s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:12:49.107899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369774922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T05:12:49.107915Z","caller":"traceutil/trace.go:171","msg":"trace[1271664370] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:630; }","duration":"1.369799012s","start":"2026-02-18T05:12:47.738113Z","end":"2026-02-18T05:12:49.107912Z","steps":["trace[1271664370] 'agreement among raft nodes before linearized reading' (duration: 1.369760141s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:49.107928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:47.738107Z","time spent":"1.369817993s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:12:49.107987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370214094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:12:49.108003Z","caller":"traceutil/trace.go:171","msg":"trace[1055645147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"1.370237695s","start":"2026-02-18T05:12:47.737762Z","end":"2026-02-18T05:12:49.108Z","steps":["trace[1055645147] 'agreement among raft nodes before linearized reading' (duration: 1.370214434s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:49.108014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:47.737757Z","time spent":"1.370254275s","remote":"127.0.0.1:56178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:12:49.108088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370872203s","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-18T05:12:49.108105Z","caller":"traceutil/trace.go:171","msg":"trace[1277445593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"1.370889194s","start":"2026-02-18T05:12:47.737212Z","end":"2026-02-18T05:12:49.108102Z","steps":["trace[1277445593] 'agreement among raft nodes before linearized reading' (duration: 1.370850792s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:49.108119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:47.737201Z","time spent":"1.370914915s","remote":"127.0.0.1:56474","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-18T05:12:49.108195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370996907s","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-18T05:12:49.108211Z","caller":"traceutil/trace.go:171","msg":"trace[1763582207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"1.371019128s","start":"2026-02-18T05:12:47.737188Z","end":"2026-02-18T05:12:49.108207Z","steps":["trace[1763582207] 'agreement among raft nodes before linearized reading' (duration: 1.370979667s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:12:49.108222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:47.737186Z","time spent":"1.371033968s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:12:53.792283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.990392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:12:53.792369Z","caller":"traceutil/trace.go:171","msg":"trace[1111195700] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"288.985762ms","start":"2026-02-18T05:12:53.503369Z","end":"2026-02-18T05:12:53.792354Z","steps":["trace[1111195700] 'read index received' (duration: 35.541µs)","trace[1111195700] 'applied index is now lower than readState.Index' (duration: 288.949071ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:53.792451Z","caller":"traceutil/trace.go:171","msg":"trace[536361982] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"372.77125ms","start":"2026-02-18T05:12:53.419667Z","end":"2026-02-18T05:12:53.792438Z","steps":["trace[536361982] 'process raft request' (duration: 42.564613ms)","trace[536361982] 'compare' (duration: 329.740664ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:53.792496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:53.419653Z","time spent":"372.821401ms","remote":"127.0.0.1:56280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:12:53.792737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.354092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:12:53.792767Z","caller":"traceutil/trace.go:171","msg":"trace[1471905487] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:667; }","duration":"289.421934ms","start":"2026-02-18T05:12:53.503335Z","end":"2026-02-18T05:12:53.792757Z","steps":["trace[1471905487] 'agreement among raft nodes before linearized reading' (duration: 289.326781ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:54.608001Z","caller":"traceutil/trace.go:171","msg":"trace[191365747] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"305.091198ms","start":"2026-02-18T05:12:54.302888Z","end":"2026-02-18T05:12:54.607979Z","steps":["trace[191365747] 'process raft request' (duration: 276.008496ms)","trace[191365747] 'compare' (duration: 28.99781ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:54.608125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:12:54.302874Z","time spent":"305.17937ms","remote":"127.0.0.1:56398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18060,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:12:55.23167Z","caller":"traceutil/trace.go:171","msg":"trace[1907831197] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"231.824417ms","start":"2026-02-18T05:12:54.999829Z","end":"2026-02-18T05:12:55.231654Z","steps":["trace[1907831197] 'process raft request' (duration: 154.283644ms)","trace[1907831197] 'compare' (duration: 77.408079ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:55.445669Z","caller":"traceutil/trace.go:171","msg":"trace[849071311] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"203.345077ms","start":"2026-02-18T05:12:55.242302Z","end":"2026-02-18T05:12:55.445647Z","steps":["trace[849071311] 'process raft request' (duration: 203.248805ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:55.445884Z","caller":"traceutil/trace.go:171","msg":"trace[105171947] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"203.573973ms","start":"2026-02-18T05:12:55.242266Z","end":"2026-02-18T05:12:55.44584Z","steps":["trace[105171947] 'read index received' (duration: 50.154263ms)","trace[105171947] 'applied index is now lower than readState.Index' (duration: 153.41888ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:12:55.446019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.730917ms","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-18T05:12:55.446041Z","caller":"traceutil/trace.go:171","msg":"trace[1533690273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:674; }","duration":"203.79687ms","start":"2026-02-18T05:12:55.242237Z","end":"2026-02-18T05:12:55.446034Z","steps":["trace[1533690273] 'agreement among raft nodes before linearized reading' (duration: 203.690527ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:55.446189Z","caller":"traceutil/trace.go:171","msg":"trace[574457761] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"201.611721ms","start":"2026-02-18T05:12:55.244572Z","end":"2026-02-18T05:12:55.446184Z","steps":["trace[574457761] 'process raft request' (duration: 201.045415ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:12:55.446345Z","caller":"traceutil/trace.go:171","msg":"trace[696209663] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"209.576815ms","start":"2026-02-18T05:12:55.236762Z","end":"2026-02-18T05:12:55.446339Z","steps":["trace[696209663] 'process raft request' (duration: 162.606587ms)","trace[696209663] 'compare' (duration: 46.060533ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:12:55.459127Z","caller":"traceutil/trace.go:171","msg":"trace[1063005944] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"156.606535ms","start":"2026-02-18T05:12:55.302505Z","end":"2026-02-18T05:12:55.459111Z","steps":["trace[1063005944] 'process raft request' (duration: 156.530383ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:02.750405Z","caller":"traceutil/trace.go:171","msg":"trace[1237359357] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"128.568322ms","start":"2026-02-18T05:13:02.621808Z","end":"2026-02-18T05:13:02.750377Z","steps":["trace[1237359357] 'process raft request' (duration: 91.940371ms)","trace[1237359357] 'compare' (duration: 36.503368ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:02.877075Z","caller":"traceutil/trace.go:171","msg":"trace[875533682] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"118.029057ms","start":"2026-02-18T05:13:02.759029Z","end":"2026-02-18T05:13:02.877058Z","steps":["trace[875533682] 'process raft request' (duration: 111.139174ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:02.877206Z","caller":"traceutil/trace.go:171","msg":"trace[1276713327] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"101.199323ms","start":"2026-02-18T05:13:02.775996Z","end":"2026-02-18T05:13:02.877196Z","steps":["trace[1276713327] 'process raft request' (duration: 100.995548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:02.877539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.690095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:13:02.877871Z","caller":"traceutil/trace.go:171","msg":"trace[379331789] range","detail":"{range_begin:/registry/resourcequotas/local-path-storage/; range_end:/registry/resourcequotas/local-path-storage0; response_count:0; response_revision:749; }","duration":"104.031204ms","start":"2026-02-18T05:13:02.77383Z","end":"2026-02-18T05:13:02.877861Z","steps":["trace[379331789] 'agreement among raft nodes before linearized reading' (duration: 103.675525ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:02.953119Z","caller":"traceutil/trace.go:171","msg":"trace[1293482584] transaction","detail":"{read_only:false; number_of_response:1; response_revision:749; }","duration":"115.739769ms","start":"2026-02-18T05:13:02.837177Z","end":"2026-02-18T05:13:02.952917Z","steps":["trace[1293482584] 'process raft request' (duration: 115.576145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:03.248294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.422282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:13:03.24854Z","caller":"traceutil/trace.go:171","msg":"trace[383080907] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"289.839613ms","start":"2026-02-18T05:13:02.958689Z","end":"2026-02-18T05:13:03.248528Z","steps":["trace[383080907] 'process raft request' (duration: 127.131444ms)","trace[383080907] 'compare' (duration: 162.303369ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:05.502187Z","caller":"traceutil/trace.go:171","msg":"trace[799509766] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"152.285107ms","start":"2026-02-18T05:13:05.349884Z","end":"2026-02-18T05:13:05.502169Z","steps":["trace[799509766] 'process raft request' (duration: 76.834216ms)","trace[799509766] 'compare' (duration: 75.173724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:13:10.058288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766993791,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:13:10.30367Z","caller":"traceutil/trace.go:171","msg":"trace[1578885826] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"773.918102ms","start":"2026-02-18T05:13:09.529715Z","end":"2026-02-18T05:13:10.303633Z","steps":["trace[1578885826] 'process raft request' (duration: 773.794469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:10.303803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:09.529703Z","time spent":"774.026794ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:13:10.305497Z","caller":"traceutil/trace.go:171","msg":"trace[332894059] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"747.784239ms","start":"2026-02-18T05:13:09.557695Z","end":"2026-02-18T05:13:10.30548Z","steps":["trace[332894059] 'read index received' (duration: 746.52789ms)","trace[332894059] 'applied index is now lower than readState.Index' (duration: 1.255599ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:10.305582Z","caller":"traceutil/trace.go:171","msg":"trace[1697851725] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"678.129493ms","start":"2026-02-18T05:13:09.627446Z","end":"2026-02-18T05:13:10.305575Z","steps":["trace[1697851725] 'process raft request' (duration: 677.943349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:10.305692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:09.627418Z","time spent":"678.214596ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:13:10.305842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.145057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T05:13:10.305932Z","caller":"traceutil/trace.go:171","msg":"trace[1940327015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"748.252649ms","start":"2026-02-18T05:13:09.557671Z","end":"2026-02-18T05:13:10.305924Z","steps":["trace[1940327015] 'agreement among raft nodes before linearized reading' (duration: 748.105096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:10.305958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:09.557659Z","time spent":"748.28848ms","remote":"127.0.0.1:56474","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-18T05:13:10.306069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.416442ms","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-18T05:13:10.306093Z","caller":"traceutil/trace.go:171","msg":"trace[1908947576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"739.460403ms","start":"2026-02-18T05:13:09.566627Z","end":"2026-02-18T05:13:10.306088Z","steps":["trace[1908947576] 'agreement among raft nodes before linearized reading' (duration: 739.391522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:10.306109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:09.566616Z","time spent":"739.488764ms","remote":"127.0.0.1:56474","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-18T05:13:10.306396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.470831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:13:10.306443Z","caller":"traceutil/trace.go:171","msg":"trace[1076416335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"445.558223ms","start":"2026-02-18T05:13:09.860878Z","end":"2026-02-18T05:13:10.306436Z","steps":["trace[1076416335] 'agreement among raft nodes before linearized reading' (duration: 445.269797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:10.306464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:09.860856Z","time spent":"445.604194ms","remote":"127.0.0.1:56178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:13:10.306698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.004308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-18T05:13:10.306732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.848254ms","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-18T05:13:10.306743Z","caller":"traceutil/trace.go:171","msg":"trace[506065020] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"732.071849ms","start":"2026-02-18T05:13:09.574658Z","end":"2026-02-18T05:13:10.30673Z","steps":["trace[506065020] 'agreement among raft nodes before linearized reading' (duration: 731.933086ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:10.306754Z","caller":"traceutil/trace.go:171","msg":"trace[1366469534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:787; }","duration":"731.891366ms","start":"2026-02-18T05:13:09.574855Z","end":"2026-02-18T05:13:10.306747Z","steps":["trace[1366469534] 'agreement among raft nodes before linearized reading' (duration: 731.827654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:10.306776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:09.574846Z","time spent":"731.925766ms","remote":"127.0.0.1:56474","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-18T05:13:10.306778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:09.574647Z","time spent":"732.12369ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T05:13:10.584588Z","caller":"traceutil/trace.go:171","msg":"trace[740622218] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"268.916244ms","start":"2026-02-18T05:13:10.315655Z","end":"2026-02-18T05:13:10.584571Z","steps":["trace[740622218] 'process raft request' (duration: 265.087655ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:10.585013Z","caller":"traceutil/trace.go:171","msg":"trace[1721271660] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"267.533512ms","start":"2026-02-18T05:13:10.317473Z","end":"2026-02-18T05:13:10.585006Z","steps":["trace[1721271660] 'process raft request' (duration: 267.511241ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:10.5852Z","caller":"traceutil/trace.go:171","msg":"trace[473894656] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"267.829739ms","start":"2026-02-18T05:13:10.317362Z","end":"2026-02-18T05:13:10.585192Z","steps":["trace[473894656] 'process raft request' (duration: 267.564513ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:10.585312Z","caller":"traceutil/trace.go:171","msg":"trace[1391380577] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"267.86203ms","start":"2026-02-18T05:13:10.317444Z","end":"2026-02-18T05:13:10.585306Z","steps":["trace[1391380577] 'process raft request' (duration: 267.521022ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:10.938682Z","caller":"traceutil/trace.go:171","msg":"trace[925564092] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"245.927469ms","start":"2026-02-18T05:13:10.692731Z","end":"2026-02-18T05:13:10.938659Z","steps":["trace[925564092] 'process raft request' (duration: 245.805766ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:14.99319Z","caller":"traceutil/trace.go:171","msg":"trace[1774191225] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"378.414607ms","start":"2026-02-18T05:13:14.614761Z","end":"2026-02-18T05:13:14.993175Z","steps":["trace[1774191225] 'read index received' (duration: 378.285244ms)","trace[1774191225] 'applied index is now lower than readState.Index' (duration: 128.843µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:14.993251Z","caller":"traceutil/trace.go:171","msg":"trace[2016529292] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"380.271369ms","start":"2026-02-18T05:13:14.612975Z","end":"2026-02-18T05:13:14.993246Z","steps":["trace[2016529292] 'process raft request' (duration: 380.114785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:14.993316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:14.612963Z","time spent":"380.302779ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:13:14.993439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.676073ms","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-18T05:13:14.993454Z","caller":"traceutil/trace.go:171","msg":"trace[1605300442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"378.700613ms","start":"2026-02-18T05:13:14.614749Z","end":"2026-02-18T05:13:14.993449Z","steps":["trace[1605300442] 'agreement among raft nodes before linearized reading' (duration: 378.632022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:14.993467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:14.614743Z","time spent":"378.721284ms","remote":"127.0.0.1:56474","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-18T05:13:14.993623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.703184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-18T05:13:14.993679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.332279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:13:14.993676Z","caller":"traceutil/trace.go:171","msg":"trace[175719454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"366.826827ms","start":"2026-02-18T05:13:14.626835Z","end":"2026-02-18T05:13:14.993662Z","steps":["trace[175719454] 'agreement among raft nodes before linearized reading' (duration: 366.672744ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:14.993694Z","caller":"traceutil/trace.go:171","msg":"trace[178578008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"134.3737ms","start":"2026-02-18T05:13:14.859316Z","end":"2026-02-18T05:13:14.993689Z","steps":["trace[178578008] 'agreement among raft nodes before linearized reading' (duration: 134.344499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:14.993703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:14.626807Z","time spent":"366.890449ms","remote":"127.0.0.1:56474","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-18T05:13:14.993854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.232888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T05:13:14.993873Z","caller":"traceutil/trace.go:171","msg":"trace[605422225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"322.28802ms","start":"2026-02-18T05:13:14.671579Z","end":"2026-02-18T05:13:14.993867Z","steps":["trace[605422225] 'agreement among raft nodes before linearized reading' (duration: 322.228978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:14.993891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:13:14.671559Z","time spent":"322.32751ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:13:15.209522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.316499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:13:15.209759Z","caller":"traceutil/trace.go:171","msg":"trace[273789030] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"211.301201ms","start":"2026-02-18T05:13:14.998434Z","end":"2026-02-18T05:13:15.209735Z","steps":["trace[273789030] 'process raft request' (duration: 54.407939ms)","trace[273789030] 'compare' (duration: 156.170426ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:15.212214Z","caller":"traceutil/trace.go:171","msg":"trace[1747281091] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"212.808455ms","start":"2026-02-18T05:13:14.999383Z","end":"2026-02-18T05:13:15.212191Z","steps":["trace[1747281091] 'process raft request' (duration: 210.312939ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:15.214937Z","caller":"traceutil/trace.go:171","msg":"trace[2020976338] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"214.625804ms","start":"2026-02-18T05:13:14.999485Z","end":"2026-02-18T05:13:15.214111Z","steps":["trace[2020976338] 'process raft request' (duration: 212.383324ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:18.513214Z","caller":"traceutil/trace.go:171","msg":"trace[543436071] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"105.028318ms","start":"2026-02-18T05:13:18.408164Z","end":"2026-02-18T05:13:18.513192Z","steps":["trace[543436071] 'process raft request' (duration: 104.731472ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:19.754033Z","caller":"traceutil/trace.go:171","msg":"trace[2032481587] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"146.438975ms","start":"2026-02-18T05:13:19.607572Z","end":"2026-02-18T05:13:19.754011Z","steps":["trace[2032481587] 'process raft request' (duration: 146.272021ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:23.245873Z","caller":"traceutil/trace.go:171","msg":"trace[69451335] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"119.760645ms","start":"2026-02-18T05:13:23.126098Z","end":"2026-02-18T05:13:23.245859Z","steps":["trace[69451335] 'process raft request' (duration: 119.409427ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:24.843108Z","caller":"traceutil/trace.go:171","msg":"trace[821560941] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"235.711009ms","start":"2026-02-18T05:13:24.607379Z","end":"2026-02-18T05:13:24.84309Z","steps":["trace[821560941] 'process raft request' (duration: 235.608537ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:25.538888Z","caller":"traceutil/trace.go:171","msg":"trace[1734138676] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"130.109903ms","start":"2026-02-18T05:13:25.408751Z","end":"2026-02-18T05:13:25.53886Z","steps":["trace[1734138676] 'process raft request' (duration: 105.907321ms)","trace[1734138676] 'compare' (duration: 23.984947ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:29.030868Z","caller":"traceutil/trace.go:171","msg":"trace[219284637] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"155.530555ms","start":"2026-02-18T05:13:28.875323Z","end":"2026-02-18T05:13:29.030853Z","steps":["trace[219284637] 'process raft request' (duration: 155.414622ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:29.726121Z","caller":"traceutil/trace.go:171","msg":"trace[1785745269] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"124.791239ms","start":"2026-02-18T05:13:29.601304Z","end":"2026-02-18T05:13:29.726095Z","steps":["trace[1785745269] 'process raft request' (duration: 80.203985ms)","trace[1785745269] 'compare' (duration: 44.465052ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:30.623639Z","caller":"traceutil/trace.go:171","msg":"trace[1252841209] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"145.236928ms","start":"2026-02-18T05:13:30.478375Z","end":"2026-02-18T05:13:30.623612Z","steps":["trace[1252841209] 'process raft request' (duration: 145.043604ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:31.846402Z","caller":"traceutil/trace.go:171","msg":"trace[743915927] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"114.450041ms","start":"2026-02-18T05:13:31.731892Z","end":"2026-02-18T05:13:31.846342Z","steps":["trace[743915927] 'process raft request' (duration: 114.329198ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:35.623577Z","caller":"traceutil/trace.go:171","msg":"trace[1689294045] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"111.485925ms","start":"2026-02-18T05:13:35.51207Z","end":"2026-02-18T05:13:35.623556Z","steps":["trace[1689294045] 'process raft request' (duration: 70.789074ms)","trace[1689294045] 'compare' (duration: 40.607079ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:35.796876Z","caller":"traceutil/trace.go:171","msg":"trace[1051319228] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"136.756397ms","start":"2026-02-18T05:13:35.660098Z","end":"2026-02-18T05:13:35.796854Z","steps":["trace[1051319228] 'process raft request' (duration: 76.699964ms)","trace[1051319228] 'compare' (duration: 59.961612ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:36.011957Z","caller":"traceutil/trace.go:171","msg":"trace[818732442] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"122.343308ms","start":"2026-02-18T05:13:35.889586Z","end":"2026-02-18T05:13:36.011929Z","steps":["trace[818732442] 'process raft request' (duration: 85.060791ms)","trace[818732442] 'compare' (duration: 37.183205ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:45.276026Z","caller":"traceutil/trace.go:171","msg":"trace[1144137844] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"109.130061ms","start":"2026-02-18T05:13:45.166879Z","end":"2026-02-18T05:13:45.276009Z","steps":["trace[1144137844] 'process raft request' (duration: 109.029919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:46.41747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.822336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4912"} {"level":"info","ts":"2026-02-18T05:13:46.417543Z","caller":"traceutil/trace.go:171","msg":"trace[1943019397] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:893; }","duration":"128.928578ms","start":"2026-02-18T05:13:46.288598Z","end":"2026-02-18T05:13:46.417526Z","steps":["trace[1943019397] 'range keys from in-memory index tree' (duration: 128.655223ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:56.872459Z","caller":"traceutil/trace.go:171","msg":"trace[110188358] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"152.746269ms","start":"2026-02-18T05:13:56.71969Z","end":"2026-02-18T05:13:56.872437Z","steps":["trace[110188358] 'process raft request' (duration: 152.352363ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:57.953534Z","caller":"traceutil/trace.go:171","msg":"trace[662383440] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"204.340297ms","start":"2026-02-18T05:13:57.749169Z","end":"2026-02-18T05:13:57.953509Z","steps":["trace[662383440] 'read index received' (duration: 204.079513ms)","trace[662383440] 'applied index is now lower than readState.Index' (duration: 259.904µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:57.95384Z","caller":"traceutil/trace.go:171","msg":"trace[1607815919] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"259.985874ms","start":"2026-02-18T05:13:57.693843Z","end":"2026-02-18T05:13:57.953829Z","steps":["trace[1607815919] 'process raft request' (duration: 259.497666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:57.954117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.928726ms","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-18T05:13:57.954155Z","caller":"traceutil/trace.go:171","msg":"trace[843341237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:939; }","duration":"205.026268ms","start":"2026-02-18T05:13:57.749119Z","end":"2026-02-18T05:13:57.954146Z","steps":["trace[843341237] 'agreement among raft nodes before linearized reading' (duration: 204.884896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:13:58.173658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.904847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:13:58.173778Z","caller":"traceutil/trace.go:171","msg":"trace[1221987608] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:980; }","duration":"179.086215ms","start":"2026-02-18T05:13:57.994675Z","end":"2026-02-18T05:13:58.173761Z","steps":["trace[1221987608] 'read index received' (duration: 54.002516ms)","trace[1221987608] 'applied index is now lower than readState.Index' (duration: 125.083029ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:58.173831Z","caller":"traceutil/trace.go:171","msg":"trace[1750122048] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"211.187827ms","start":"2026-02-18T05:13:57.962637Z","end":"2026-02-18T05:13:58.173825Z","steps":["trace[1750122048] 'process raft request' (duration: 211.094246ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:58.173837Z","caller":"traceutil/trace.go:171","msg":"trace[632641486] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"214.969217ms","start":"2026-02-18T05:13:57.958845Z","end":"2026-02-18T05:13:58.173814Z","steps":["trace[632641486] 'process raft request' (duration: 89.807047ms)","trace[632641486] 'compare' (duration: 124.826995ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:13:58.174276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.599454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9450ba4c-f704-4e8b-8cf6-f50da7f990b5\" ","response":"range_response_count:1 size:3809"} {"level":"info","ts":"2026-02-18T05:13:58.174301Z","caller":"traceutil/trace.go:171","msg":"trace[1641573170] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9450ba4c-f704-4e8b-8cf6-f50da7f990b5; range_end:; response_count:1; response_revision:941; }","duration":"179.657345ms","start":"2026-02-18T05:13:57.994636Z","end":"2026-02-18T05:13:58.174294Z","steps":["trace[1641573170] 'agreement among raft nodes before linearized reading' (duration: 179.547593ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:58.864078Z","caller":"traceutil/trace.go:171","msg":"trace[185711652] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"132.767692ms","start":"2026-02-18T05:13:58.731293Z","end":"2026-02-18T05:13:58.864061Z","steps":["trace[185711652] 'process raft request' (duration: 132.259014ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:59.306093Z","caller":"traceutil/trace.go:171","msg":"trace[157151005] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"120.23559ms","start":"2026-02-18T05:13:59.185835Z","end":"2026-02-18T05:13:59.306071Z","steps":["trace[157151005] 'process raft request' (duration: 120.090588ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:59.501217Z","caller":"traceutil/trace.go:171","msg":"trace[508214582] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"104.984791ms","start":"2026-02-18T05:13:59.396211Z","end":"2026-02-18T05:13:59.501195Z","steps":["trace[508214582] 'read index received' (duration: 50.626246ms)","trace[508214582] 'applied index is now lower than readState.Index' (duration: 54.357555ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:13:59.50175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.518509ms","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-18T05:13:59.501937Z","caller":"traceutil/trace.go:171","msg":"trace[1815696048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:949; }","duration":"105.775103ms","start":"2026-02-18T05:13:59.396149Z","end":"2026-02-18T05:13:59.501924Z","steps":["trace[1815696048] 'agreement among raft nodes before linearized reading' (duration: 105.364187ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:13:59.502433Z","caller":"traceutil/trace.go:171","msg":"trace[1948138513] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"107.773075ms","start":"2026-02-18T05:13:59.394645Z","end":"2026-02-18T05:13:59.502418Z","steps":["trace[1948138513] 'process raft request' (duration: 52.051329ms)","trace[1948138513] 'compare' (duration: 54.406665ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:13:59.517011Z","caller":"traceutil/trace.go:171","msg":"trace[985196982] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"118.099268ms","start":"2026-02-18T05:13:59.398901Z","end":"2026-02-18T05:13:59.517Z","steps":["trace[985196982] 'process raft request' (duration: 118.024896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:03.523586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:03.177024Z","time spent":"346.558724ms","remote":"127.0.0.1:56218","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T05:14:04.382973Z","caller":"traceutil/trace.go:171","msg":"trace[1739682137] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"131.141059ms","start":"2026-02-18T05:14:04.251808Z","end":"2026-02-18T05:14:04.382949Z","steps":["trace[1739682137] 'process raft request' (duration: 113.054533ms)","trace[1739682137] 'compare' (duration: 17.838312ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:09.867291Z","caller":"traceutil/trace.go:171","msg":"trace[975900527] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"221.892142ms","start":"2026-02-18T05:14:09.645382Z","end":"2026-02-18T05:14:09.867274Z","steps":["trace[975900527] 'process raft request' (duration: 219.368695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:10.625715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.222441ms","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-18T05:14:10.625765Z","caller":"traceutil/trace.go:171","msg":"trace[1106151948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"529.318602ms","start":"2026-02-18T05:14:10.096435Z","end":"2026-02-18T05:14:10.625754Z","steps":["trace[1106151948] 'range keys from in-memory index tree' (duration: 529.068788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:10.625786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:10.096419Z","time spent":"529.362852ms","remote":"127.0.0.1:56474","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-18T05:14:10.62588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.195821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:10.625895Z","caller":"traceutil/trace.go:171","msg":"trace[1062701053] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:993; }","duration":"495.241921ms","start":"2026-02-18T05:14:10.130647Z","end":"2026-02-18T05:14:10.625889Z","steps":["trace[1062701053] 'count revisions from in-memory index tree' (duration: 495.103579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:10.62591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:10.13063Z","time spent":"495.274451ms","remote":"127.0.0.1:56706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:14:10.626036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.076186ms","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-18T05:14:10.626048Z","caller":"traceutil/trace.go:171","msg":"trace[1678864069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:993; }","duration":"284.130097ms","start":"2026-02-18T05:14:10.341914Z","end":"2026-02-18T05:14:10.626044Z","steps":["trace[1678864069] 'range keys from in-memory index tree' (duration: 283.998745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:10.626166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.953613ms","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-18T05:14:10.62618Z","caller":"traceutil/trace.go:171","msg":"trace[1363798201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:993; }","duration":"207.067434ms","start":"2026-02-18T05:14:10.419107Z","end":"2026-02-18T05:14:10.626174Z","steps":["trace[1363798201] 'range keys from in-memory index tree' (duration: 206.845951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:10.626245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.535399ms","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-18T05:14:10.626258Z","caller":"traceutil/trace.go:171","msg":"trace[1553325198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:993; }","duration":"179.5736ms","start":"2026-02-18T05:14:10.446681Z","end":"2026-02-18T05:14:10.626254Z","steps":["trace[1553325198] 'range keys from in-memory index tree' (duration: 179.461838ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:10.811894Z","caller":"traceutil/trace.go:171","msg":"trace[713708689] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"180.585785ms","start":"2026-02-18T05:14:10.631283Z","end":"2026-02-18T05:14:10.811868Z","steps":["trace[713708689] 'process raft request' (duration: 180.438793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:11.515129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088531766994365,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:14:11.905388Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.0936684s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:14:11.906721Z","caller":"traceutil/trace.go:171","msg":"trace[983122711] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"1.274810823s","start":"2026-02-18T05:14:10.631884Z","end":"2026-02-18T05:14:11.906695Z","steps":["trace[983122711] 'process raft request' (duration: 1.274087712s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:11.906958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:10.631879Z","time spent":"1.275000326s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:11.907266Z","caller":"traceutil/trace.go:171","msg":"trace[1985491833] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.18619977s","start":"2026-02-18T05:14:10.721053Z","end":"2026-02-18T05:14:11.907253Z","steps":["trace[1985491833] 'process raft request' (duration: 1.186133869s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:11.907834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:10.721034Z","time spent":"1.186422254s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:11.907913Z","caller":"traceutil/trace.go:171","msg":"trace[1134318878] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"1.274888274s","start":"2026-02-18T05:14:10.632973Z","end":"2026-02-18T05:14:11.907861Z","steps":["trace[1134318878] 'process raft request' (duration: 1.273649406s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:11.908089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:10.632965Z","time spent":"1.275031886s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:11.908461Z","caller":"traceutil/trace.go:171","msg":"trace[8271619] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"1.274229264s","start":"2026-02-18T05:14:10.634216Z","end":"2026-02-18T05:14:11.908445Z","steps":["trace[8271619] 'process raft request' (duration: 1.272910805s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:11.908574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:10.634196Z","time spent":"1.274307175s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:11.913778Z","caller":"traceutil/trace.go:171","msg":"trace[126515321] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1038; }","duration":"899.267272ms","start":"2026-02-18T05:14:11.014501Z","end":"2026-02-18T05:14:11.913768Z","steps":["trace[126515321] 'read index received' (duration: 891.450338ms)","trace[126515321] 'applied index is now lower than readState.Index' (duration: 7.816354ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:11.914426Z","caller":"traceutil/trace.go:171","msg":"trace[1502671690] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"751.431602ms","start":"2026-02-18T05:14:11.162985Z","end":"2026-02-18T05:14:11.914417Z","steps":["trace[1502671690] 'process raft request' (duration: 750.720711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:11.914512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:11.162961Z","time spent":"751.492493ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:14:11.91468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.180605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:11.914708Z","caller":"traceutil/trace.go:171","msg":"trace[1933844693] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:999; }","duration":"900.240766ms","start":"2026-02-18T05:14:11.01446Z","end":"2026-02-18T05:14:11.914701Z","steps":["trace[1933844693] 'agreement among raft nodes before linearized reading' (duration: 900.195105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:11.914754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:11.014444Z","time spent":"900.304288ms","remote":"127.0.0.1:56368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-18T05:14:11.914947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.222199ms","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-18T05:14:11.914972Z","caller":"traceutil/trace.go:171","msg":"trace[1378046536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:999; }","duration":"272.28199ms","start":"2026-02-18T05:14:11.642684Z","end":"2026-02-18T05:14:11.914966Z","steps":["trace[1378046536] 'agreement among raft nodes before linearized reading' (duration: 272.198228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:12.286607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.921359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:12.28735Z","caller":"traceutil/trace.go:171","msg":"trace[1974287100] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"366.862718ms","start":"2026-02-18T05:14:11.920468Z","end":"2026-02-18T05:14:12.287331Z","steps":["trace[1974287100] 'read index received' (duration: 99.190838ms)","trace[1974287100] 'applied index is now lower than readState.Index' (duration: 267.66962ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:12.287627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.147242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:12.287776Z","caller":"traceutil/trace.go:171","msg":"trace[1575477062] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1000; }","duration":"367.316724ms","start":"2026-02-18T05:14:11.920446Z","end":"2026-02-18T05:14:12.287762Z","steps":["trace[1575477062] 'agreement among raft nodes before linearized reading' (duration: 367.067371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:12.287932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:11.920434Z","time spent":"367.481446ms","remote":"127.0.0.1:56368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-02-18T05:14:12.288682Z","caller":"traceutil/trace.go:171","msg":"trace[1515253884] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"368.263248ms","start":"2026-02-18T05:14:11.920402Z","end":"2026-02-18T05:14:12.288665Z","steps":["trace[1515253884] 'process raft request' (duration: 99.209328ms)","trace[1515253884] 'compare' (duration: 266.823708ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:12.289245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:11.920393Z","time spent":"368.461351ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:13.013421Z","caller":"traceutil/trace.go:171","msg":"trace[1647755333] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"191.404252ms","start":"2026-02-18T05:14:12.821992Z","end":"2026-02-18T05:14:13.013397Z","steps":["trace[1647755333] 'process raft request' (duration: 191.238799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:13.453481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.512565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T05:14:13.453673Z","caller":"traceutil/trace.go:171","msg":"trace[1048086467] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:1001; }","duration":"276.650607ms","start":"2026-02-18T05:14:13.176896Z","end":"2026-02-18T05:14:13.453546Z","steps":["trace[1048086467] 'range keys from in-memory index tree' (duration: 276.336433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:14.774454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.519467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:14.776058Z","caller":"traceutil/trace.go:171","msg":"trace[677139576] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"915.876992ms","start":"2026-02-18T05:14:13.858656Z","end":"2026-02-18T05:14:14.774533Z","steps":["trace[677139576] 'read index received' (duration: 306.213173ms)","trace[677139576] 'applied index is now lower than readState.Index' (duration: 609.662839ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:14.776296Z","caller":"traceutil/trace.go:171","msg":"trace[1142384222] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.147001811s","start":"2026-02-18T05:14:13.629275Z","end":"2026-02-18T05:14:14.776277Z","steps":["trace[1142384222] 'process raft request' (duration: 535.590947ms)","trace[1142384222] 'compare' (duration: 608.563183ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:14.776479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:13.629261Z","time spent":"1.147074311s","remote":"127.0.0.1:56218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:14:14.77698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.322817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:14.777024Z","caller":"traceutil/trace.go:171","msg":"trace[1603886315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"918.396168ms","start":"2026-02-18T05:14:13.858615Z","end":"2026-02-18T05:14:14.777011Z","steps":["trace[1603886315] 'agreement among raft nodes before linearized reading' (duration: 918.265606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:14.777286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:13.8586Z","time spent":"918.604501ms","remote":"127.0.0.1:56178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:14:14.777794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.954419ms","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-18T05:14:14.777852Z","caller":"traceutil/trace.go:171","msg":"trace[892526020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1002; }","duration":"866.01445ms","start":"2026-02-18T05:14:13.911824Z","end":"2026-02-18T05:14:14.777838Z","steps":["trace[892526020] 'agreement among raft nodes before linearized reading' (duration: 865.864668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:14.778404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:13.911781Z","time spent":"866.511838ms","remote":"127.0.0.1:56474","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-18T05:14:14.778833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.848439ms","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-18T05:14:14.778873Z","caller":"traceutil/trace.go:171","msg":"trace[244889051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"485.923061ms","start":"2026-02-18T05:14:14.29294Z","end":"2026-02-18T05:14:14.778864Z","steps":["trace[244889051] 'agreement among raft nodes before linearized reading' (duration: 485.816439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:14.778902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:14.292927Z","time spent":"485.969321ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:14:14.779541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.267229ms","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-18T05:14:14.779582Z","caller":"traceutil/trace.go:171","msg":"trace[1802977447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"865.31887ms","start":"2026-02-18T05:14:13.914254Z","end":"2026-02-18T05:14:14.779573Z","steps":["trace[1802977447] 'agreement among raft nodes before linearized reading' (duration: 865.200378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:14.779747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:13.914249Z","time spent":"865.486542ms","remote":"127.0.0.1:56474","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-18T05:14:14.780064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.333675ms","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-18T05:14:14.780102Z","caller":"traceutil/trace.go:171","msg":"trace[986001303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"866.385206ms","start":"2026-02-18T05:14:13.913708Z","end":"2026-02-18T05:14:14.780093Z","steps":["trace[986001303] 'agreement among raft nodes before linearized reading' (duration: 866.286264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:14.780162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:13.9137Z","time spent":"866.452716ms","remote":"127.0.0.1:56474","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-18T05:14:15.091955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.57519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:15.092034Z","caller":"traceutil/trace.go:171","msg":"trace[2074528981] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"305.677236ms","start":"2026-02-18T05:14:14.786341Z","end":"2026-02-18T05:14:15.092018Z","steps":["trace[2074528981] 'process raft request' (duration: 183.977854ms)","trace[2074528981] 'compare' (duration: 121.409608ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:15.092074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:14.786324Z","time spent":"305.732256ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:14:15.839636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.803052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:15.839857Z","caller":"traceutil/trace.go:171","msg":"trace[1026807314] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1047; }","duration":"1.051800246s","start":"2026-02-18T05:14:14.788041Z","end":"2026-02-18T05:14:15.839842Z","steps":["trace[1026807314] 'read index received' (duration: 182.35801ms)","trace[1026807314] 'applied index is now lower than readState.Index' (duration: 869.441316ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:15.840065Z","caller":"traceutil/trace.go:171","msg":"trace[834147434] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"1.05347844s","start":"2026-02-18T05:14:14.786574Z","end":"2026-02-18T05:14:15.840053Z","steps":["trace[834147434] 'process raft request' (duration: 737.189851ms)","trace[834147434] 'compare' (duration: 315.275664ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:15.840128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:14.786561Z","time spent":"1.053533342s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:15.840328Z","caller":"traceutil/trace.go:171","msg":"trace[355508876] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"1.053049674s","start":"2026-02-18T05:14:14.787267Z","end":"2026-02-18T05:14:15.840317Z","steps":["trace[355508876] 'process raft request' (duration: 1.052461265s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:15.84039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:14.787255Z","time spent":"1.053102045s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:14:15.840536Z","caller":"traceutil/trace.go:171","msg":"trace[566420756] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"1.052665839s","start":"2026-02-18T05:14:14.787861Z","end":"2026-02-18T05:14:15.840527Z","steps":["trace[566420756] 'process raft request' (duration: 1.051925028s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:15.840589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:14.787855Z","time spent":"1.052704329s","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:14:15.840752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052712779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-18T05:14:15.84078Z","caller":"traceutil/trace.go:171","msg":"trace[321643448] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1006; }","duration":"1.052740199s","start":"2026-02-18T05:14:14.788031Z","end":"2026-02-18T05:14:15.840771Z","steps":["trace[321643448] 'agreement among raft nodes before linearized reading' (duration: 1.052678608s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:15.840807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:14.788014Z","time spent":"1.052786621s","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-18T05:14:15.840971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.296753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:15.840999Z","caller":"traceutil/trace.go:171","msg":"trace[2058414582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"981.343753ms","start":"2026-02-18T05:14:14.859648Z","end":"2026-02-18T05:14:15.840991Z","steps":["trace[2058414582] 'agreement among raft nodes before linearized reading' (duration: 981.264352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:15.841023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:14.859636Z","time spent":"981.381894ms","remote":"127.0.0.1:56178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:14:15.841332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.235797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:14:15.841363Z","caller":"traceutil/trace.go:171","msg":"trace[106366971] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1006; }","duration":"740.302288ms","start":"2026-02-18T05:14:15.101051Z","end":"2026-02-18T05:14:15.841354Z","steps":["trace[106366971] 'agreement among raft nodes before linearized reading' (duration: 740.242637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:15.841392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:15.101041Z","time spent":"740.343688ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:14:15.843683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.473006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T05:14:15.843777Z","caller":"traceutil/trace.go:171","msg":"trace[1864596819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1006; }","duration":"823.607928ms","start":"2026-02-18T05:14:15.020153Z","end":"2026-02-18T05:14:15.843761Z","steps":["trace[1864596819] 'agreement among raft nodes before linearized reading' (duration: 821.391676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:15.843849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:14:15.019987Z","time spent":"823.849951ms","remote":"127.0.0.1:56474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T05:14:15.953106Z","caller":"traceutil/trace.go:171","msg":"trace[1696369881] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"101.087565ms","start":"2026-02-18T05:14:15.852004Z","end":"2026-02-18T05:14:15.953091Z","steps":["trace[1696369881] 'process raft request' (duration: 98.445767ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:30.135665Z","caller":"traceutil/trace.go:171","msg":"trace[1080203223] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"161.873123ms","start":"2026-02-18T05:14:29.973767Z","end":"2026-02-18T05:14:30.13564Z","steps":["trace[1080203223] 'process raft request' (duration: 139.894932ms)","trace[1080203223] 'compare' (duration: 21.718768ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:30.137271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.079962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T05:14:30.137549Z","caller":"traceutil/trace.go:171","msg":"trace[470690801] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1049; }","duration":"127.393825ms","start":"2026-02-18T05:14:30.010141Z","end":"2026-02-18T05:14:30.137535Z","steps":["trace[470690801] 'agreement among raft nodes before linearized reading' (duration: 126.98909ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:30.137889Z","caller":"traceutil/trace.go:171","msg":"trace[1050805517] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"125.316008ms","start":"2026-02-18T05:14:30.010174Z","end":"2026-02-18T05:14:30.13549Z","steps":["trace[1050805517] 'read index received' (duration: 103.395308ms)","trace[1050805517] 'applied index is now lower than readState.Index' (duration: 21.91971ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:42.259303Z","caller":"traceutil/trace.go:171","msg":"trace[658744627] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"135.738603ms","start":"2026-02-18T05:14:42.123542Z","end":"2026-02-18T05:14:42.259281Z","steps":["trace[658744627] 'read index received' (duration: 135.528221ms)","trace[658744627] 'applied index is now lower than readState.Index' (duration: 208.952µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:42.259821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.25643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-18T05:14:42.260533Z","caller":"traceutil/trace.go:171","msg":"trace[835223700] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:1; response_revision:1080; }","duration":"136.989749ms","start":"2026-02-18T05:14:42.123525Z","end":"2026-02-18T05:14:42.260515Z","steps":["trace[835223700] 'agreement among raft nodes before linearized reading' (duration: 136.216619ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:42.260989Z","caller":"traceutil/trace.go:171","msg":"trace[1474087981] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"147.877785ms","start":"2026-02-18T05:14:42.113097Z","end":"2026-02-18T05:14:42.260975Z","steps":["trace[1474087981] 'process raft request' (duration: 146.023162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:42.260094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.910819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-18T05:14:42.265746Z","caller":"traceutil/trace.go:171","msg":"trace[1540838292] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:1; response_revision:1080; }","duration":"115.58497ms","start":"2026-02-18T05:14:42.150141Z","end":"2026-02-18T05:14:42.265726Z","steps":["trace[1540838292] 'agreement among raft nodes before linearized reading' (duration: 109.707586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:42.470711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.877652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:14:42.471697Z","caller":"traceutil/trace.go:171","msg":"trace[1998511963] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"201.719562ms","start":"2026-02-18T05:14:42.269922Z","end":"2026-02-18T05:14:42.471641Z","steps":["trace[1998511963] 'process raft request' (duration: 201.59106ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:42.471689Z","caller":"traceutil/trace.go:171","msg":"trace[2051394227] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"204.282843ms","start":"2026-02-18T05:14:42.267376Z","end":"2026-02-18T05:14:42.471658Z","steps":["trace[2051394227] 'process raft request' (duration: 88.365028ms)","trace[2051394227] 'compare' (duration: 114.71598ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:14:42.47271Z","caller":"traceutil/trace.go:171","msg":"trace[611043232] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"205.087724ms","start":"2026-02-18T05:14:42.267608Z","end":"2026-02-18T05:14:42.472696Z","steps":["trace[611043232] 'read index received' (duration: 88.142487ms)","trace[611043232] 'applied index is now lower than readState.Index' (duration: 116.943807ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:14:42.47294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.289567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-18T05:14:42.473296Z","caller":"traceutil/trace.go:171","msg":"trace[927254600] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:1; response_revision:1083; }","duration":"205.682901ms","start":"2026-02-18T05:14:42.267597Z","end":"2026-02-18T05:14:42.47328Z","steps":["trace[927254600] 'agreement among raft nodes before linearized reading' (duration: 205.258606ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:14:42.474212Z","caller":"traceutil/trace.go:171","msg":"trace[1127275403] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"205.062023ms","start":"2026-02-18T05:14:42.269135Z","end":"2026-02-18T05:14:42.474197Z","steps":["trace[1127275403] 'process raft request' (duration: 202.217197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:14:42.53252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.42609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-18T05:14:42.532874Z","caller":"traceutil/trace.go:171","msg":"trace[1218622531] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:1; response_revision:1085; }","duration":"202.805145ms","start":"2026-02-18T05:14:42.330042Z","end":"2026-02-18T05:14:42.532847Z","steps":["trace[1218622531] 'agreement among raft nodes before linearized reading' (duration: 202.4253ms)"],"step_count":1}