{"level":"warn","ts":"2026-02-19T05:53:07.873592Z","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-19T05:53:07.873702Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-19T05:53:07.873794Z","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-19T05:53:07.873813Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-19T05:53:07.873841Z","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-19T05:53:07.875053Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-02-19T05:53:07.875163Z","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":8,"max-cpu-available":8,"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.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-19T05:53:07.881436Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.973308ms"} {"level":"info","ts":"2026-02-19T05:53:07.887503Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-02-19T05:53:07.887585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T05:53:07.88761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-02-19T05:53:07.887619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T05:53:07.887632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-02-19T05:53:07.887674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-02-19T05:53:07.897053Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T05:53:07.898411Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T05:53:07.899766Z","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-19T05:53:07.902793Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T05:53:07.902996Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T05:53:07.903083Z","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-19T05:53:07.904182Z","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-19T05:53:07.904225Z","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-19T05:53:07.904961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-02-19T05:53:07.9052Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-19T05:53:07.906998Z","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-19T05:53:07.907071Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-19T05:53:07.907177Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-19T05:53:07.907996Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T05:53:07.908031Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T05:53:08.388716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T05:53:08.388802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T05:53:08.388882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-02-19T05:53:08.388908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-02-19T05:53:08.388922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-19T05:53:08.38894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-02-19T05:53:08.388956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-19T05:53:08.45269Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T05:53:08.454037Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T05:53:08.454133Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T05:53:08.454096Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T05:53:08.457677Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T05:53:08.457905Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T05:53:08.458151Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T05:53:08.458205Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T05:53:08.459692Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-02-19T05:53:08.459833Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T05:53:08.459902Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T05:53:26.084826Z","caller":"traceutil/trace.go:171","msg":"trace[1883204675] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"143.313447ms","start":"2026-02-19T05:53:25.941489Z","end":"2026-02-19T05:53:26.084802Z","steps":["trace[1883204675] 'process raft request' (duration: 143.231235ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:53:31.928866Z","caller":"traceutil/trace.go:171","msg":"trace[424783913] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"260.768267ms","start":"2026-02-19T05:53:31.668075Z","end":"2026-02-19T05:53:31.928843Z","steps":["trace[424783913] 'process raft request' (duration: 234.875022ms)","trace[424783913] 'compare' (duration: 25.775782ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:53:35.743523Z","caller":"traceutil/trace.go:171","msg":"trace[946056557] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"368.481492ms","start":"2026-02-19T05:53:35.375014Z","end":"2026-02-19T05:53:35.743496Z","steps":["trace[946056557] 'read index received' (duration: 367.731207ms)","trace[946056557] 'applied index is now lower than readState.Index' (duration: 749.145µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:53:35.74366Z","caller":"traceutil/trace.go:171","msg":"trace[1319159097] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"798.480622ms","start":"2026-02-19T05:53:34.945142Z","end":"2026-02-19T05:53:35.743622Z","steps":["trace[1319159097] 'process raft request' (duration: 797.995016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:35.743791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.750571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:53:35.743965Z","caller":"traceutil/trace.go:171","msg":"trace[1376339310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"368.976257ms","start":"2026-02-19T05:53:35.374966Z","end":"2026-02-19T05:53:35.743942Z","steps":["trace[1376339310] 'agreement among raft nodes before linearized reading' (duration: 368.674358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:35.744247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:34.945111Z","time spent":"798.636376ms","remote":"127.0.0.1:50930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317276,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T05:53:35.744073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:35.374942Z","time spent":"369.117482ms","remote":"127.0.0.1:50856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T05:53:36.086178Z","caller":"traceutil/trace.go:171","msg":"trace[1765375792] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"315.479762ms","start":"2026-02-19T05:53:35.770666Z","end":"2026-02-19T05:53:36.086146Z","steps":["trace[1765375792] 'process raft request' (duration: 285.035732ms)","trace[1765375792] 'compare' (duration: 29.405577ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:53:36.086261Z","caller":"traceutil/trace.go:171","msg":"trace[1554633724] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"315.544204ms","start":"2026-02-19T05:53:35.77069Z","end":"2026-02-19T05:53:36.086234Z","steps":["trace[1554633724] 'process raft request' (duration: 315.449671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:36.086304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:35.770646Z","time spent":"315.599895ms","remote":"127.0.0.1:51034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":505,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T05:53:36.086403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:35.770666Z","time spent":"315.673898ms","remote":"127.0.0.1:51034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T05:53:37.161453Z","caller":"traceutil/trace.go:171","msg":"trace[1905617893] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"419.463218ms","start":"2026-02-19T05:53:36.741964Z","end":"2026-02-19T05:53:37.161427Z","steps":["trace[1905617893] 'process raft request' (duration: 419.265991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:37.161908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:36.741943Z","time spent":"419.608102ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:53:38.360533Z","caller":"traceutil/trace.go:171","msg":"trace[1336015105] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"256.820413ms","start":"2026-02-19T05:53:38.103692Z","end":"2026-02-19T05:53:38.360512Z","steps":["trace[1336015105] 'read index received' (duration: 256.655247ms)","trace[1336015105] 'applied index is now lower than readState.Index' (duration: 164.626µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:53:38.360606Z","caller":"traceutil/trace.go:171","msg":"trace[1466562230] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"353.642072ms","start":"2026-02-19T05:53:38.006932Z","end":"2026-02-19T05:53:38.360574Z","steps":["trace[1466562230] 'process raft request' (duration: 353.465617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:38.360676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.974727ms","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-19T05:53:38.36071Z","caller":"traceutil/trace.go:171","msg":"trace[1254761788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"257.025458ms","start":"2026-02-19T05:53:38.103676Z","end":"2026-02-19T05:53:38.360702Z","steps":["trace[1254761788] 'agreement among raft nodes before linearized reading' (duration: 256.946866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:38.360748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:38.006895Z","time spent":"353.774626ms","remote":"127.0.0.1:51008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4212,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:53:38.360833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.627035ms","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-19T05:53:38.360913Z","caller":"traceutil/trace.go:171","msg":"trace[1998123344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"229.7738ms","start":"2026-02-19T05:53:38.131121Z","end":"2026-02-19T05:53:38.360895Z","steps":["trace[1998123344] 'agreement among raft nodes before linearized reading' (duration: 229.543772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:40.202504Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.033199673s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T05:53:40.202831Z","caller":"traceutil/trace.go:171","msg":"trace[1287081318] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.033628607s","start":"2026-02-19T05:53:39.169178Z","end":"2026-02-19T05:53:40.202807Z","steps":["trace[1287081318] 'process raft request' (duration: 1.033500273s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:40.202982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:39.169158Z","time spent":"1.033749342s","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:53:41.51222Z","caller":"traceutil/trace.go:171","msg":"trace[1657805244] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"155.49733ms","start":"2026-02-19T05:53:41.356701Z","end":"2026-02-19T05:53:41.512198Z","steps":["trace[1657805244] 'process raft request' (duration: 99.166936ms)","trace[1657805244] 'compare' (duration: 56.041747ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:53:41.70279Z","caller":"traceutil/trace.go:171","msg":"trace[1895029317] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"131.848381ms","start":"2026-02-19T05:53:41.570915Z","end":"2026-02-19T05:53:41.702764Z","steps":["trace[1895029317] 'process raft request' (duration: 126.426586ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:53:42.292685Z","caller":"traceutil/trace.go:171","msg":"trace[99018589] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:549; }","duration":"578.755388ms","start":"2026-02-19T05:53:41.713901Z","end":"2026-02-19T05:53:42.292656Z","steps":["trace[99018589] 'read index received' (duration: 59.691282ms)","trace[99018589] 'applied index is now lower than readState.Index' (duration: 519.063106ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:53:42.292847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.928203ms","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-19T05:53:42.292839Z","caller":"traceutil/trace.go:171","msg":"trace[1857040642] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"579.24608ms","start":"2026-02-19T05:53:41.713551Z","end":"2026-02-19T05:53:42.292797Z","steps":["trace[1857040642] 'process raft request' (duration: 579.042705ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:53:42.292895Z","caller":"traceutil/trace.go:171","msg":"trace[522532198] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:533; }","duration":"579.020275ms","start":"2026-02-19T05:53:41.713851Z","end":"2026-02-19T05:53:42.292871Z","steps":["trace[522532198] 'agreement among raft nodes before linearized reading' (duration: 578.942373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:42.292942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:41.713831Z","time spent":"579.096096ms","remote":"127.0.0.1:50978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" "} {"level":"info","ts":"2026-02-19T05:53:42.292986Z","caller":"traceutil/trace.go:171","msg":"trace[855499442] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"580.11466ms","start":"2026-02-19T05:53:41.712843Z","end":"2026-02-19T05:53:42.292957Z","steps":["trace[855499442] 'process raft request' (duration: 569.931653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:42.293021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:41.713544Z","time spent":"579.378233ms","remote":"127.0.0.1:51236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3328,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:53:42.293108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:41.712832Z","time spent":"580.212502ms","remote":"127.0.0.1:50924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":761,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T05:53:42.293146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.807548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-19T05:53:42.293207Z","caller":"traceutil/trace.go:171","msg":"trace[1545417840] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:533; }","duration":"447.91625ms","start":"2026-02-19T05:53:41.845275Z","end":"2026-02-19T05:53:42.293192Z","steps":["trace[1545417840] 'agreement among raft nodes before linearized reading' (duration: 447.796778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:42.293242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:41.845255Z","time spent":"447.978981ms","remote":"127.0.0.1:50930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12751,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-02-19T05:53:42.485046Z","caller":"traceutil/trace.go:171","msg":"trace[164391045] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:560; }","duration":"128.426567ms","start":"2026-02-19T05:53:42.356595Z","end":"2026-02-19T05:53:42.485022Z","steps":["trace[164391045] 'read index received' (duration: 97.467307ms)","trace[164391045] 'applied index is now lower than readState.Index' (duration: 30.95828ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:53:42.485208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.591852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"warn","ts":"2026-02-19T05:53:42.48522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.118219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:53:42.485244Z","caller":"traceutil/trace.go:171","msg":"trace[274731870] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:542; }","duration":"128.691674ms","start":"2026-02-19T05:53:42.35654Z","end":"2026-02-19T05:53:42.485232Z","steps":["trace[274731870] 'agreement among raft nodes before linearized reading' (duration: 128.601462ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:53:42.485216Z","caller":"traceutil/trace.go:171","msg":"trace[1702599554] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"152.098388ms","start":"2026-02-19T05:53:42.333073Z","end":"2026-02-19T05:53:42.485171Z","steps":["trace[1702599554] 'process raft request' (duration: 121.037935ms)","trace[1702599554] 'compare' (duration: 30.806487ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:53:42.485265Z","caller":"traceutil/trace.go:171","msg":"trace[1307128295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"109.199901ms","start":"2026-02-19T05:53:42.376053Z","end":"2026-02-19T05:53:42.485253Z","steps":["trace[1307128295] 'agreement among raft nodes before linearized reading' (duration: 109.118819ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:53:44.065342Z","caller":"traceutil/trace.go:171","msg":"trace[1604842362] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"131.068611ms","start":"2026-02-19T05:53:43.934246Z","end":"2026-02-19T05:53:44.065315Z","steps":["trace[1604842362] 'process raft request' (duration: 130.880176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:44.501211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.506017ms","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-19T05:53:44.501274Z","caller":"traceutil/trace.go:171","msg":"trace[280362353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:546; }","duration":"185.611899ms","start":"2026-02-19T05:53:44.315648Z","end":"2026-02-19T05:53:44.501259Z","steps":["trace[280362353] 'range keys from in-memory index tree' (duration: 185.353403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:44.50138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.739492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:53:44.501431Z","caller":"traceutil/trace.go:171","msg":"trace[1788590392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"119.843585ms","start":"2026-02-19T05:53:44.381575Z","end":"2026-02-19T05:53:44.501419Z","steps":["trace[1788590392] 'range keys from in-memory index tree' (duration: 119.612499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:44.968333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.467675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:53:44.968521Z","caller":"traceutil/trace.go:171","msg":"trace[429756671] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"392.822358ms","start":"2026-02-19T05:53:44.575681Z","end":"2026-02-19T05:53:44.968504Z","steps":["trace[429756671] 'process raft request' (duration: 392.758156ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:53:44.968626Z","caller":"traceutil/trace.go:171","msg":"trace[1680666641] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"393.348339ms","start":"2026-02-19T05:53:44.575263Z","end":"2026-02-19T05:53:44.968611Z","steps":["trace[1680666641] 'process raft request' (duration: 212.553102ms)","trace[1680666641] 'compare' (duration: 179.016005ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:53:44.968694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:44.575245Z","time spent":"393.413031ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:53:44.968949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:44.575664Z","time spent":"393.257447ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:53:49.021905Z","caller":"traceutil/trace.go:171","msg":"trace[1147023586] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"343.396996ms","start":"2026-02-19T05:53:48.678489Z","end":"2026-02-19T05:53:49.021886Z","steps":["trace[1147023586] 'read index received' (duration: 343.16842ms)","trace[1147023586] 'applied index is now lower than readState.Index' (duration: 227.876µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:53:49.02202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.521219ms","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-19T05:53:49.022039Z","caller":"traceutil/trace.go:171","msg":"trace[1229947165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"343.58672ms","start":"2026-02-19T05:53:48.678447Z","end":"2026-02-19T05:53:49.022033Z","steps":["trace[1229947165] 'agreement among raft nodes before linearized reading' (duration: 343.514118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:49.022063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:48.678433Z","time spent":"343.625522ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T05:53:49.022026Z","caller":"traceutil/trace.go:171","msg":"trace[1698051381] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"430.94223ms","start":"2026-02-19T05:53:48.591051Z","end":"2026-02-19T05:53:49.021993Z","steps":["trace[1698051381] 'process raft request' (duration: 430.671984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:49.022214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:48.591031Z","time spent":"431.101724ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:53:49.260997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.285695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:53:49.261113Z","caller":"traceutil/trace.go:171","msg":"trace[1101070704] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"187.239209ms","start":"2026-02-19T05:53:49.073856Z","end":"2026-02-19T05:53:49.261096Z","steps":["trace[1101070704] 'read index received' (duration: 45.690648ms)","trace[1101070704] 'applied index is now lower than readState.Index' (duration: 141.547431ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:53:49.261152Z","caller":"traceutil/trace.go:171","msg":"trace[1671215156] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"234.688358ms","start":"2026-02-19T05:53:49.026428Z","end":"2026-02-19T05:53:49.261117Z","steps":["trace[1671215156] 'process raft request' (duration: 93.190719ms)","trace[1671215156] 'compare' (duration: 141.166021ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:53:49.261219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.364181ms","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-19T05:53:49.26125Z","caller":"traceutil/trace.go:171","msg":"trace[2025091998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"187.458423ms","start":"2026-02-19T05:53:49.073781Z","end":"2026-02-19T05:53:49.261239Z","steps":["trace[2025091998] 'agreement among raft nodes before linearized reading' (duration: 187.398642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:49.261259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.630384ms","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-19T05:53:49.261288Z","caller":"traceutil/trace.go:171","msg":"trace[800084067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"186.709676ms","start":"2026-02-19T05:53:49.074571Z","end":"2026-02-19T05:53:49.261281Z","steps":["trace[800084067] 'agreement among raft nodes before linearized reading' (duration: 186.642194ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:53:49.458283Z","caller":"traceutil/trace.go:171","msg":"trace[1753147372] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"194.03635ms","start":"2026-02-19T05:53:49.264217Z","end":"2026-02-19T05:53:49.458254Z","steps":["trace[1753147372] 'process raft request' (duration: 145.643998ms)","trace[1753147372] 'compare' (duration: 48.223278ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:53:49.458577Z","caller":"traceutil/trace.go:171","msg":"trace[775547294] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"193.723524ms","start":"2026-02-19T05:53:49.264799Z","end":"2026-02-19T05:53:49.458522Z","steps":["trace[775547294] 'process raft request' (duration: 193.409226ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:53:50.815762Z","caller":"traceutil/trace.go:171","msg":"trace[899639900] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"228.316089ms","start":"2026-02-19T05:53:50.587421Z","end":"2026-02-19T05:53:50.815737Z","steps":["trace[899639900] 'process raft request' (duration: 228.153665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:51.606908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.063081ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-19T05:53:51.606992Z","caller":"traceutil/trace.go:171","msg":"trace[1860347839] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"145.041593ms","start":"2026-02-19T05:53:51.461939Z","end":"2026-02-19T05:53:51.60698Z","steps":["trace[1860347839] 'read index received' (duration: 40.808448ms)","trace[1860347839] 'applied index is now lower than readState.Index' (duration: 104.232235ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:53:51.607098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.49611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-19T05:53:51.60713Z","caller":"traceutil/trace.go:171","msg":"trace[1464844149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"144.574532ms","start":"2026-02-19T05:53:51.462548Z","end":"2026-02-19T05:53:51.607123Z","steps":["trace[1464844149] 'agreement among raft nodes before linearized reading' (duration: 144.50094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:51.60719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.233548ms","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-19T05:53:51.607273Z","caller":"traceutil/trace.go:171","msg":"trace[1150350821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:562; }","duration":"145.376941ms","start":"2026-02-19T05:53:51.461878Z","end":"2026-02-19T05:53:51.607255Z","steps":["trace[1150350821] 'agreement among raft nodes before linearized reading' (duration: 145.171366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:51.921772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.215835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:53:51.922149Z","caller":"traceutil/trace.go:171","msg":"trace[1895831812] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"311.018544ms","start":"2026-02-19T05:53:51.611102Z","end":"2026-02-19T05:53:51.922121Z","steps":["trace[1895831812] 'process raft request' (duration: 115.972219ms)","trace[1895831812] 'compare' (duration: 193.954779ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:53:51.922274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:51.611085Z","time spent":"311.138637ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:53:51.922915Z","caller":"traceutil/trace.go:171","msg":"trace[1262351595] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"240.019525ms","start":"2026-02-19T05:53:51.682824Z","end":"2026-02-19T05:53:51.922843Z","steps":["trace[1262351595] 'read index received' (duration: 44.358855ms)","trace[1262351595] 'applied index is now lower than readState.Index' (duration: 195.65879ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:53:51.923308Z","caller":"traceutil/trace.go:171","msg":"trace[151797761] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"311.150068ms","start":"2026-02-19T05:53:51.612107Z","end":"2026-02-19T05:53:51.923257Z","steps":["trace[151797761] 'process raft request' (duration: 309.772485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:51.923584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:51.612025Z","time spent":"311.389083ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:53:51.924163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.326837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-19T05:53:51.924239Z","caller":"traceutil/trace.go:171","msg":"trace[216321092] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:565; }","duration":"241.414649ms","start":"2026-02-19T05:53:51.682794Z","end":"2026-02-19T05:53:51.924209Z","steps":["trace[216321092] 'agreement among raft nodes before linearized reading' (duration: 240.972878ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:53:52.118404Z","caller":"traceutil/trace.go:171","msg":"trace[470207933] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"432.848878ms","start":"2026-02-19T05:53:51.685516Z","end":"2026-02-19T05:53:52.118365Z","steps":["trace[470207933] 'process raft request' (duration: 359.510474ms)","trace[470207933] 'compare' (duration: 73.19114ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:53:52.118558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:51.685506Z","time spent":"432.977911ms","remote":"127.0.0.1:50924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T05:53:52.118404Z","caller":"traceutil/trace.go:171","msg":"trace[1740986756] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"195.412816ms","start":"2026-02-19T05:53:51.922962Z","end":"2026-02-19T05:53:52.118375Z","steps":["trace[1740986756] 'read index received' (duration: 122.112223ms)","trace[1740986756] 'applied index is now lower than readState.Index' (duration: 73.298883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:53:52.118765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.042168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-19T05:53:52.11882Z","caller":"traceutil/trace.go:171","msg":"trace[1666372786] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:566; }","duration":"354.312954ms","start":"2026-02-19T05:53:51.764493Z","end":"2026-02-19T05:53:52.118806Z","steps":["trace[1666372786] 'agreement among raft nodes before linearized reading' (duration: 353.964396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:52.118959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:51.764413Z","time spent":"354.53191ms","remote":"127.0.0.1:50954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-19T05:53:52.119184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.390776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-19T05:53:52.119296Z","caller":"traceutil/trace.go:171","msg":"trace[801834012] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:566; }","duration":"193.53471ms","start":"2026-02-19T05:53:51.92574Z","end":"2026-02-19T05:53:52.119275Z","steps":["trace[801834012] 'agreement among raft nodes before linearized reading' (duration: 193.317314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:52.875356Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529275979314135,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T05:53:53.376241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529275979314135,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T05:53:53.876941Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529275979314135,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T05:53:54.375631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000858389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-19T05:53:54.37572Z","caller":"traceutil/trace.go:171","msg":"trace[1121469841] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001006252s","start":"2026-02-19T05:53:52.3747Z","end":"2026-02-19T05:53:54.375706Z","steps":["trace[1121469841] 'agreement among raft nodes before linearized reading' (duration: 2.00089095s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:54.375757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:52.374679Z","time spent":"2.001070863s","remote":"127.0.0.1:50856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/19 05:53:54 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-19T05:53:54.377839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529275979314135,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T05:53:54.825503Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.646697323s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T05:53:54.825975Z","caller":"traceutil/trace.go:171","msg":"trace[570588606] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"2.647319559s","start":"2026-02-19T05:53:52.178632Z","end":"2026-02-19T05:53:54.825951Z","steps":["trace[570588606] 'process raft request' (duration: 2.647176986s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:54.826197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:52.178596Z","time spent":"2.647450072s","remote":"127.0.0.1:51012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:53:54.848708Z","caller":"traceutil/trace.go:171","msg":"trace[2046705809] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"2.473929148s","start":"2026-02-19T05:53:52.374752Z","end":"2026-02-19T05:53:54.848681Z","steps":["trace[2046705809] 'read index received' (duration: 2.451052056s)","trace[2046705809] 'applied index is now lower than readState.Index' (duration: 22.876012ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:53:54.848765Z","caller":"traceutil/trace.go:171","msg":"trace[1550403492] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"633.734748ms","start":"2026-02-19T05:53:54.215009Z","end":"2026-02-19T05:53:54.848744Z","steps":["trace[1550403492] 'process raft request' (duration: 633.520133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:54.848875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:54.214986Z","time spent":"633.828651ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:53:54.848897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.766056461s","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-19T05:53:54.848935Z","caller":"traceutil/trace.go:171","msg":"trace[1023030827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"1.766173545s","start":"2026-02-19T05:53:53.08275Z","end":"2026-02-19T05:53:54.848923Z","steps":["trace[1023030827] 'agreement among raft nodes before linearized reading' (duration: 1.766069972s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:54.848926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.065678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:53:54.848971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:53.082727Z","time spent":"1.766235805s","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-19T05:53:54.848993Z","caller":"traceutil/trace.go:171","msg":"trace[232379232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"473.157741ms","start":"2026-02-19T05:53:54.375821Z","end":"2026-02-19T05:53:54.848978Z","steps":["trace[232379232] 'agreement among raft nodes before linearized reading' (duration: 473.059238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:54.849038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:54.375804Z","time spent":"473.215182ms","remote":"127.0.0.1:50846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:53:54.849138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.030684ms","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-19T05:53:54.84918Z","caller":"traceutil/trace.go:171","msg":"trace[384401253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"921.097256ms","start":"2026-02-19T05:53:53.928073Z","end":"2026-02-19T05:53:54.84917Z","steps":["trace[384401253] 'agreement among raft nodes before linearized reading' (duration: 921.026615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:54.84921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:53.928057Z","time spent":"921.146197ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T05:53:54.849244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512204871s","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-19T05:53:54.849283Z","caller":"traceutil/trace.go:171","msg":"trace[604607169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"1.512245142s","start":"2026-02-19T05:53:53.337028Z","end":"2026-02-19T05:53:54.849273Z","steps":["trace[604607169] 'agreement among raft nodes before linearized reading' (duration: 1.51218116s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:54.849322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.169259ms","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-19T05:53:54.849317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:53.336968Z","time spent":"1.512343045s","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T05:53:54.849363Z","caller":"traceutil/trace.go:171","msg":"trace[1115872904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"919.23786ms","start":"2026-02-19T05:53:53.930116Z","end":"2026-02-19T05:53:54.849354Z","steps":["trace[1115872904] 'agreement among raft nodes before linearized reading' (duration: 919.167558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:54.849396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:53.930096Z","time spent":"919.293872ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T05:53:54.985561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.350239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:53:54.985683Z","caller":"traceutil/trace.go:171","msg":"trace[309911724] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"133.052402ms","start":"2026-02-19T05:53:54.852606Z","end":"2026-02-19T05:53:54.985658Z","steps":["trace[309911724] 'process raft request' (duration: 30.505518ms)","trace[309911724] 'compare' (duration: 102.234716ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:53:55.080843Z","caller":"traceutil/trace.go:171","msg":"trace[556973458] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"227.610527ms","start":"2026-02-19T05:53:54.853204Z","end":"2026-02-19T05:53:55.080814Z","steps":["trace[556973458] 'process raft request' (duration: 227.559676ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:53:55.080853Z","caller":"traceutil/trace.go:171","msg":"trace[284600042] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"227.807312ms","start":"2026-02-19T05:53:54.853015Z","end":"2026-02-19T05:53:55.080822Z","steps":["trace[284600042] 'process raft request' (duration: 227.669959ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:53:55.08099Z","caller":"traceutil/trace.go:171","msg":"trace[1955746285] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"227.735891ms","start":"2026-02-19T05:53:54.853249Z","end":"2026-02-19T05:53:55.080985Z","steps":["trace[1955746285] 'process raft request' (duration: 227.535905ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:53:55.08121Z","caller":"traceutil/trace.go:171","msg":"trace[268719683] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:590; }","duration":"224.720018ms","start":"2026-02-19T05:53:54.856461Z","end":"2026-02-19T05:53:55.081181Z","steps":["trace[268719683] 'read index received' (duration: 26.659995ms)","trace[268719683] 'applied index is now lower than readState.Index' (duration: 198.058693ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:53:55.081306Z","caller":"traceutil/trace.go:171","msg":"trace[1401022368] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"227.414374ms","start":"2026-02-19T05:53:54.853878Z","end":"2026-02-19T05:53:55.081292Z","steps":["trace[1401022368] 'process raft request' (duration: 227.085705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:55.082373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.887576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:53:55.08243Z","caller":"traceutil/trace.go:171","msg":"trace[1141769505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"225.982868ms","start":"2026-02-19T05:53:54.856434Z","end":"2026-02-19T05:53:55.082417Z","steps":["trace[1141769505] 'agreement among raft nodes before linearized reading' (duration: 224.867971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:57.823866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.638883ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-19T05:53:57.823968Z","caller":"traceutil/trace.go:171","msg":"trace[1181616861] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"833.986779ms","start":"2026-02-19T05:53:56.989964Z","end":"2026-02-19T05:53:57.82395Z","steps":["trace[1181616861] 'read index received' (duration: 277.345576ms)","trace[1181616861] 'applied index is now lower than readState.Index' (duration: 556.639743ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:53:57.824121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.146092ms","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-19T05:53:57.824149Z","caller":"traceutil/trace.go:171","msg":"trace[1291407558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"834.217904ms","start":"2026-02-19T05:53:56.98992Z","end":"2026-02-19T05:53:57.824138Z","steps":["trace[1291407558] 'agreement among raft nodes before linearized reading' (duration: 834.138162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:57.824183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:56.989899Z","time spent":"834.275906ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T05:53:57.824181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.409211ms","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-19T05:53:57.824195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.562547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:53:57.824216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.038971ms","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-19T05:53:57.824263Z","caller":"traceutil/trace.go:171","msg":"trace[453946080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"451.704881ms","start":"2026-02-19T05:53:57.37254Z","end":"2026-02-19T05:53:57.824245Z","steps":["trace[453946080] 'agreement among raft nodes before linearized reading' (duration: 451.589478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:57.824307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.174624ms","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-19T05:53:57.824309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:57.372514Z","time spent":"451.786932ms","remote":"127.0.0.1:50846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T05:53:57.824309Z","caller":"traceutil/trace.go:171","msg":"trace[2026137977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"738.178565ms","start":"2026-02-19T05:53:57.086107Z","end":"2026-02-19T05:53:57.824285Z","steps":["trace[2026137977] 'agreement among raft nodes before linearized reading' (duration: 737.99641ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:53:57.824335Z","caller":"traceutil/trace.go:171","msg":"trace[557825889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"738.222056ms","start":"2026-02-19T05:53:57.086105Z","end":"2026-02-19T05:53:57.824327Z","steps":["trace[557825889] 'agreement among raft nodes before linearized reading' (duration: 738.176275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:57.824368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:57.086092Z","time spent":"738.268297ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T05:53:57.824373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:57.086086Z","time spent":"738.273187ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-19T05:53:57.824256Z","caller":"traceutil/trace.go:171","msg":"trace[410737494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"738.539484ms","start":"2026-02-19T05:53:57.085695Z","end":"2026-02-19T05:53:57.824235Z","steps":["trace[410737494] 'agreement among raft nodes before linearized reading' (duration: 738.38648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:57.824507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:57.085664Z","time spent":"738.833141ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T05:53:58.873894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529275979314150,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T05:53:59.366399Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.537300524s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T05:53:59.366907Z","caller":"traceutil/trace.go:171","msg":"trace[1948550009] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"1.537890408s","start":"2026-02-19T05:53:57.828986Z","end":"2026-02-19T05:53:59.366876Z","steps":["trace[1948550009] 'process raft request' (duration: 1.537700813s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:53:59.367109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:57.828964Z","time spent":"1.538033502s","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:54:00.374206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000557769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-19T05:54:00.374293Z","caller":"traceutil/trace.go:171","msg":"trace[598540278] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000688893s","start":"2026-02-19T05:53:58.373584Z","end":"2026-02-19T05:54:00.374273Z","steps":["trace[598540278] 'agreement among raft nodes before linearized reading' (duration: 2.00056376s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:54:00.37434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:58.373568Z","time spent":"2.000762114s","remote":"127.0.0.1:50856","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-19T05:54:00.852368Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.396394459s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T05:54:00.852848Z","caller":"traceutil/trace.go:171","msg":"trace[544875588] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:596; }","duration":"2.479190925s","start":"2026-02-19T05:53:58.373613Z","end":"2026-02-19T05:54:00.852804Z","steps":["trace[544875588] 'read index received' (duration: 993.058105ms)","trace[544875588] 'applied index is now lower than readState.Index' (duration: 1.48613195s)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:54:00.85293Z","caller":"traceutil/trace.go:171","msg":"trace[1329242174] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"3.023413902s","start":"2026-02-19T05:53:57.829449Z","end":"2026-02-19T05:54:00.852863Z","steps":["trace[1329242174] 'process raft request' (duration: 3.0233046s)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:54:00.852924Z","caller":"traceutil/trace.go:171","msg":"trace[1252174180] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"3.023398773s","start":"2026-02-19T05:53:57.829506Z","end":"2026-02-19T05:54:00.852905Z","steps":["trace[1252174180] 'process raft request' (duration: 3.023271309s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:54:00.852999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.230415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:54:00.85303Z","caller":"traceutil/trace.go:171","msg":"trace[236335758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"478.319687ms","start":"2026-02-19T05:54:00.374703Z","end":"2026-02-19T05:54:00.853023Z","steps":["trace[236335758] 'agreement among raft nodes before linearized reading' (duration: 478.252086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:54:00.853056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:54:00.374675Z","time spent":"478.375429ms","remote":"127.0.0.1:50846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:54:00.853075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:57.829498Z","time spent":"3.023500825s","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:54:00.852923Z","caller":"traceutil/trace.go:171","msg":"trace[343887512] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"3.023465594s","start":"2026-02-19T05:53:57.829421Z","end":"2026-02-19T05:54:00.852886Z","steps":["trace[343887512] 'process raft request' (duration: 3.023201268s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:54:00.853208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:57.829433Z","time spent":"3.023586126s","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:54:00.853222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:53:57.829395Z","time spent":"3.02373243s","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:54:01.547298Z","caller":"traceutil/trace.go:171","msg":"trace[1848120026] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"173.987555ms","start":"2026-02-19T05:54:01.373281Z","end":"2026-02-19T05:54:01.547269Z","steps":["trace[1848120026] 'read index received' (duration: 128.793838ms)","trace[1848120026] 'applied index is now lower than readState.Index' (duration: 45.191427ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:54:01.547425Z","caller":"traceutil/trace.go:171","msg":"trace[1178638963] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"293.296797ms","start":"2026-02-19T05:54:01.254116Z","end":"2026-02-19T05:54:01.547413Z","steps":["trace[1178638963] 'process raft request' (duration: 247.975777ms)","trace[1178638963] 'compare' (duration: 44.656103ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:54:01.547659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.359385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-19T05:54:01.547688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.342453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-19T05:54:01.547781Z","caller":"traceutil/trace.go:171","msg":"trace[2060279290] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:584; }","duration":"127.495177ms","start":"2026-02-19T05:54:01.420266Z","end":"2026-02-19T05:54:01.547761Z","steps":["trace[2060279290] 'agreement among raft nodes before linearized reading' (duration: 127.277322ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:54:01.547722Z","caller":"traceutil/trace.go:171","msg":"trace[1605629246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"174.474818ms","start":"2026-02-19T05:54:01.373233Z","end":"2026-02-19T05:54:01.547708Z","steps":["trace[1605629246] 'agreement among raft nodes before linearized reading' (duration: 174.320984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:54:02.995276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.776247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:54:02.995464Z","caller":"traceutil/trace.go:171","msg":"trace[752921208] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"670.585751ms","start":"2026-02-19T05:54:02.324845Z","end":"2026-02-19T05:54:02.995431Z","steps":["trace[752921208] 'process raft request' (duration: 448.590379ms)","trace[752921208] 'compare' (duration: 221.649394ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:54:02.995595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:54:02.324826Z","time spent":"670.707615ms","remote":"127.0.0.1:50924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T05:54:02.995611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.901322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:54:02.995662Z","caller":"traceutil/trace.go:171","msg":"trace[160324447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"623.990925ms","start":"2026-02-19T05:54:02.371655Z","end":"2026-02-19T05:54:02.995646Z","steps":["trace[160324447] 'agreement among raft nodes before linearized reading' (duration: 623.866032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:54:02.995711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:54:02.371633Z","time spent":"624.067707ms","remote":"127.0.0.1:50856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T05:54:02.995467Z","caller":"traceutil/trace.go:171","msg":"trace[148911662] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"623.688607ms","start":"2026-02-19T05:54:02.371697Z","end":"2026-02-19T05:54:02.995386Z","steps":["trace[148911662] 'read index received' (duration: 401.707095ms)","trace[148911662] 'applied index is now lower than readState.Index' (duration: 221.979952ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:54:02.996172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.6696ms","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-19T05:54:02.996234Z","caller":"traceutil/trace.go:171","msg":"trace[1887321936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"138.772462ms","start":"2026-02-19T05:54:02.857443Z","end":"2026-02-19T05:54:02.996216Z","steps":["trace[1887321936] 'agreement among raft nodes before linearized reading' (duration: 138.671499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:54:02.996584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.807312ms","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-19T05:54:02.996926Z","caller":"traceutil/trace.go:171","msg":"trace[303184098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"139.197142ms","start":"2026-02-19T05:54:02.85771Z","end":"2026-02-19T05:54:02.996907Z","steps":["trace[303184098] 'agreement among raft nodes before linearized reading' (duration: 138.756751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:54:02.996609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.71377ms","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-19T05:54:02.998116Z","caller":"traceutil/trace.go:171","msg":"trace[18367666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"140.079445ms","start":"2026-02-19T05:54:02.857815Z","end":"2026-02-19T05:54:02.997894Z","steps":["trace[18367666] 'agreement among raft nodes before linearized reading' (duration: 138.637488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:54:05.575329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.826817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:54:05.575419Z","caller":"traceutil/trace.go:171","msg":"trace[495690518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"200.973981ms","start":"2026-02-19T05:54:05.374424Z","end":"2026-02-19T05:54:05.575398Z","steps":["trace[495690518] 'range keys from in-memory index tree' (duration: 200.665123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:54:05.575506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.299287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-19T05:54:05.575578Z","caller":"traceutil/trace.go:171","msg":"trace[1573085279] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:648; }","duration":"115.424551ms","start":"2026-02-19T05:54:05.460132Z","end":"2026-02-19T05:54:05.575556Z","steps":["trace[1573085279] 'range keys from in-memory index tree' (duration: 115.210845ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:54:14.414575Z","caller":"traceutil/trace.go:171","msg":"trace[368638388] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"106.471864ms","start":"2026-02-19T05:54:14.308058Z","end":"2026-02-19T05:54:14.414529Z","steps":["trace[368638388] 'process raft request' (duration: 94.019068ms)","trace[368638388] 'compare' (duration: 12.251021ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:54:14.683063Z","caller":"traceutil/trace.go:171","msg":"trace[1164739572] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"146.902012ms","start":"2026-02-19T05:54:14.536128Z","end":"2026-02-19T05:54:14.68303Z","steps":["trace[1164739572] 'process raft request' (duration: 135.687029ms)","trace[1164739572] 'compare' (duration: 11.086839ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:54:19.785776Z","caller":"traceutil/trace.go:171","msg":"trace[1176281946] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"104.649121ms","start":"2026-02-19T05:54:19.681105Z","end":"2026-02-19T05:54:19.785754Z","steps":["trace[1176281946] 'process raft request' (duration: 104.509118ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:54:37.3811Z","caller":"traceutil/trace.go:171","msg":"trace[585867633] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"115.445074ms","start":"2026-02-19T05:54:37.265603Z","end":"2026-02-19T05:54:37.381048Z","steps":["trace[585867633] 'process raft request' (duration: 105.965183ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:54:38.075084Z","caller":"traceutil/trace.go:171","msg":"trace[1562371173] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"125.655978ms","start":"2026-02-19T05:54:37.949404Z","end":"2026-02-19T05:54:38.07506Z","steps":["trace[1562371173] 'process raft request' (duration: 125.516515ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:54:39.570034Z","caller":"traceutil/trace.go:171","msg":"trace[1388305234] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"113.186092ms","start":"2026-02-19T05:54:39.456799Z","end":"2026-02-19T05:54:39.569985Z","steps":["trace[1388305234] 'process raft request' (duration: 81.746344ms)","trace[1388305234] 'compare' (duration: 31.305685ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:54:52.415483Z","caller":"traceutil/trace.go:171","msg":"trace[482691393] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"202.95709ms","start":"2026-02-19T05:54:52.212495Z","end":"2026-02-19T05:54:52.415453Z","steps":["trace[482691393] 'process raft request' (duration: 202.733244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:06.150352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.618114ms","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-19T05:55:06.150428Z","caller":"traceutil/trace.go:171","msg":"trace[504012147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:922; }","duration":"471.745329ms","start":"2026-02-19T05:55:05.678667Z","end":"2026-02-19T05:55:06.150413Z","steps":["trace[504012147] 'range keys from in-memory index tree' (duration: 471.47444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:06.150469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:55:05.678656Z","time spent":"471.80383ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-19T05:55:06.15044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.854135ms","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-19T05:55:06.15053Z","caller":"traceutil/trace.go:171","msg":"trace[694349157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"539.99113ms","start":"2026-02-19T05:55:05.610518Z","end":"2026-02-19T05:55:06.150509Z","steps":["trace[694349157] 'range keys from in-memory index tree' (duration: 539.702891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:06.150587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:55:05.610508Z","time spent":"540.066941ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T05:55:06.1506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.737642ms","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-19T05:55:06.150724Z","caller":"traceutil/trace.go:171","msg":"trace[1380687377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"539.886407ms","start":"2026-02-19T05:55:05.610808Z","end":"2026-02-19T05:55:06.150695Z","steps":["trace[1380687377] 'range keys from in-memory index tree' (duration: 539.557237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:06.150793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:55:05.610803Z","time spent":"539.974039ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-19T05:55:12.705218Z","caller":"traceutil/trace.go:171","msg":"trace[1295771750] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"175.664688ms","start":"2026-02-19T05:55:12.529519Z","end":"2026-02-19T05:55:12.705184Z","steps":["trace[1295771750] 'process raft request' (duration: 175.479983ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:20.445508Z","caller":"traceutil/trace.go:171","msg":"trace[1182908626] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"191.824823ms","start":"2026-02-19T05:55:20.253652Z","end":"2026-02-19T05:55:20.445476Z","steps":["trace[1182908626] 'read index received' (duration: 191.549375ms)","trace[1182908626] 'applied index is now lower than readState.Index' (duration: 274.578µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:20.445673Z","caller":"traceutil/trace.go:171","msg":"trace[1730936062] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"194.519732ms","start":"2026-02-19T05:55:20.251115Z","end":"2026-02-19T05:55:20.445635Z","steps":["trace[1730936062] 'process raft request' (duration: 194.1195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:20.445903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.117771ms","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-19T05:55:20.445967Z","caller":"traceutil/trace.go:171","msg":"trace[1720856769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:985; }","duration":"192.322498ms","start":"2026-02-19T05:55:20.253632Z","end":"2026-02-19T05:55:20.445955Z","steps":["trace[1720856769] 'agreement among raft nodes before linearized reading' (duration: 192.012849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:20.44612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.682758ms","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-19T05:55:20.446253Z","caller":"traceutil/trace.go:171","msg":"trace[1598403911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"191.851853ms","start":"2026-02-19T05:55:20.254374Z","end":"2026-02-19T05:55:20.446226Z","steps":["trace[1598403911] 'agreement among raft nodes before linearized reading' (duration: 191.474522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:21.639729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.777631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:55:21.639898Z","caller":"traceutil/trace.go:171","msg":"trace[1512602118] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:988; }","duration":"339.989057ms","start":"2026-02-19T05:55:21.299885Z","end":"2026-02-19T05:55:21.639874Z","steps":["trace[1512602118] 'count revisions from in-memory index tree' (duration: 339.692099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:21.639709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.796304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-19T05:55:21.640477Z","caller":"traceutil/trace.go:171","msg":"trace[1284744347] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:988; }","duration":"215.148674ms","start":"2026-02-19T05:55:21.424868Z","end":"2026-02-19T05:55:21.640016Z","steps":["trace[1284744347] 'range keys from in-memory index tree' (duration: 214.549947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:21.639455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.032612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:55:21.639733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.152699ms","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-19T05:55:21.640679Z","caller":"traceutil/trace.go:171","msg":"trace[225725477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:988; }","duration":"266.334399ms","start":"2026-02-19T05:55:21.374317Z","end":"2026-02-19T05:55:21.640652Z","steps":["trace[225725477] 'range keys from in-memory index tree' (duration: 264.922309ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:21.640716Z","caller":"traceutil/trace.go:171","msg":"trace[2000960052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:988; }","duration":"149.159398ms","start":"2026-02-19T05:55:21.491531Z","end":"2026-02-19T05:55:21.64069Z","steps":["trace[2000960052] 'range keys from in-memory index tree' (duration: 148.004305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:21.640488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:55:21.299818Z","time spent":"340.170752ms","remote":"127.0.0.1:40620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-19T05:55:29.899315Z","caller":"traceutil/trace.go:171","msg":"trace[719108229] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"161.27692ms","start":"2026-02-19T05:55:29.738009Z","end":"2026-02-19T05:55:29.899286Z","steps":["trace[719108229] 'read index received' (duration: 161.127596ms)","trace[719108229] 'applied index is now lower than readState.Index' (duration: 148.164µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:29.899366Z","caller":"traceutil/trace.go:171","msg":"trace[43503688] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"170.163529ms","start":"2026-02-19T05:55:29.72917Z","end":"2026-02-19T05:55:29.899334Z","steps":["trace[43503688] 'process raft request' (duration: 169.900232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:29.89955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.134854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:914"} {"level":"warn","ts":"2026-02-19T05:55:29.899547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.521208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-02-19T05:55:29.899592Z","caller":"traceutil/trace.go:171","msg":"trace[1519276079] range","detail":"{range_begin:/registry/clusterroles/system:controller:horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1016; }","duration":"127.218596ms","start":"2026-02-19T05:55:29.772358Z","end":"2026-02-19T05:55:29.899577Z","steps":["trace[1519276079] 'agreement among raft nodes before linearized reading' (duration: 127.101902ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:29.899608Z","caller":"traceutil/trace.go:171","msg":"trace[201263653] range","detail":"{range_begin:/registry/clusterroles/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:1016; }","duration":"161.61142ms","start":"2026-02-19T05:55:29.737981Z","end":"2026-02-19T05:55:29.899592Z","steps":["trace[201263653] 'agreement among raft nodes before linearized reading' (duration: 161.456074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:30.188374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.673267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:namespace-controller\" ","response":"range_response_count:1 size:725"} {"level":"info","ts":"2026-02-19T05:55:30.188459Z","caller":"traceutil/trace.go:171","msg":"trace[542751350] range","detail":"{range_begin:/registry/clusterroles/system:controller:namespace-controller; range_end:; response_count:1; response_revision:1016; }","duration":"119.804651ms","start":"2026-02-19T05:55:30.068633Z","end":"2026-02-19T05:55:30.188438Z","steps":["trace[542751350] 'range keys from in-memory index tree' (duration: 119.508732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:30.188591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.962807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:job-controller\" ","response":"range_response_count:1 size:782"} {"level":"info","ts":"2026-02-19T05:55:30.188639Z","caller":"traceutil/trace.go:171","msg":"trace[1913823921] range","detail":"{range_begin:/registry/clusterroles/system:controller:job-controller; range_end:; response_count:1; response_revision:1016; }","duration":"118.08093ms","start":"2026-02-19T05:55:30.070544Z","end":"2026-02-19T05:55:30.188625Z","steps":["trace[1913823921] 'range keys from in-memory index tree' (duration: 117.887516ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:30.822195Z","caller":"traceutil/trace.go:171","msg":"trace[72617933] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1061; }","duration":"168.433583ms","start":"2026-02-19T05:55:30.653736Z","end":"2026-02-19T05:55:30.822169Z","steps":["trace[72617933] 'read index received' (duration: 36.534808ms)","trace[72617933] 'applied index is now lower than readState.Index' (duration: 131.897505ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:30.822351Z","caller":"traceutil/trace.go:171","msg":"trace[2054936089] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"173.385978ms","start":"2026-02-19T05:55:30.64887Z","end":"2026-02-19T05:55:30.822255Z","steps":["trace[2054936089] 'process raft request' (duration: 104.625617ms)","trace[2054936089] 'compare' (duration: 68.363439ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:55:30.822441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.68344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:665"} {"level":"info","ts":"2026-02-19T05:55:30.822516Z","caller":"traceutil/trace.go:171","msg":"trace[1861325942] range","detail":"{range_begin:/registry/clusterroles/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:1019; }","duration":"168.780783ms","start":"2026-02-19T05:55:30.653715Z","end":"2026-02-19T05:55:30.822496Z","steps":["trace[1861325942] 'agreement among raft nodes before linearized reading' (duration: 168.631048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:30.82257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.111958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-02-19T05:55:30.822616Z","caller":"traceutil/trace.go:171","msg":"trace[726650095] range","detail":"{range_begin:/registry/clusterroles/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:1019; }","duration":"149.197441ms","start":"2026-02-19T05:55:30.673407Z","end":"2026-02-19T05:55:30.822604Z","steps":["trace[726650095] 'agreement among raft nodes before linearized reading' (duration: 149.089498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:31.080397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.527857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:55:31.080538Z","caller":"traceutil/trace.go:171","msg":"trace[1582968918] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"109.211853ms","start":"2026-02-19T05:55:30.971302Z","end":"2026-02-19T05:55:31.080514Z","steps":["trace[1582968918] 'read index received' (duration: 4.364638ms)","trace[1582968918] 'applied index is now lower than readState.Index' (duration: 104.845575ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:31.080612Z","caller":"traceutil/trace.go:171","msg":"trace[1175911087] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"159.037589ms","start":"2026-02-19T05:55:30.921548Z","end":"2026-02-19T05:55:31.080585Z","steps":["trace[1175911087] 'process raft request' (duration: 54.206065ms)","trace[1175911087] 'compare' (duration: 104.375531ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:55:31.080817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.205379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:843"} {"level":"warn","ts":"2026-02-19T05:55:31.080801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.494201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:728"} {"level":"info","ts":"2026-02-19T05:55:31.080882Z","caller":"traceutil/trace.go:171","msg":"trace[1857135372] range","detail":"{range_begin:/registry/clusterroles/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:1020; }","duration":"101.305682ms","start":"2026-02-19T05:55:30.979559Z","end":"2026-02-19T05:55:31.080865Z","steps":["trace[1857135372] 'agreement among raft nodes before linearized reading' (duration: 101.203469ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:31.080908Z","caller":"traceutil/trace.go:171","msg":"trace[1605106166] range","detail":"{range_begin:/registry/clusterroles/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:1020; }","duration":"109.632765ms","start":"2026-02-19T05:55:30.971255Z","end":"2026-02-19T05:55:31.080887Z","steps":["trace[1605106166] 'agreement among raft nodes before linearized reading' (duration: 109.44713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:31.396472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.098944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replication-controller\" ","response":"range_response_count:1 size:830"} {"level":"warn","ts":"2026-02-19T05:55:31.396493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.017089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium\" ","response":"range_response_count:1 size:749"} {"level":"info","ts":"2026-02-19T05:55:31.396582Z","caller":"traceutil/trace.go:171","msg":"trace[462810601] range","detail":"{range_begin:/registry/clusterroles/system:controller:replication-controller; range_end:; response_count:1; response_revision:1020; }","duration":"145.234229ms","start":"2026-02-19T05:55:31.251305Z","end":"2026-02-19T05:55:31.396539Z","steps":["trace[462810601] 'range keys from in-memory index tree' (duration: 144.986321ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:31.396649Z","caller":"traceutil/trace.go:171","msg":"trace[733514326] range","detail":"{range_begin:/registry/clusterrolebindings/cilium; range_end:; response_count:1; response_revision:1020; }","duration":"136.123782ms","start":"2026-02-19T05:55:31.26043Z","end":"2026-02-19T05:55:31.396553Z","steps":["trace[733514326] 'range keys from in-memory index tree' (duration: 135.963308ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:31.682251Z","caller":"traceutil/trace.go:171","msg":"trace[1792779897] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"157.529317ms","start":"2026-02-19T05:55:31.524688Z","end":"2026-02-19T05:55:31.682217Z","steps":["trace[1792779897] 'process raft request' (duration: 79.791823ms)","trace[1792779897] 'compare' (duration: 77.574429ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:32.29192Z","caller":"traceutil/trace.go:171","msg":"trace[1346092546] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"385.540502ms","start":"2026-02-19T05:55:31.906353Z","end":"2026-02-19T05:55:32.291894Z","steps":["trace[1346092546] 'process raft request' (duration: 385.402677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:32.292082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:55:31.906335Z","time spent":"385.668396ms","remote":"127.0.0.1:51102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:55:32.292509Z","caller":"traceutil/trace.go:171","msg":"trace[845967873] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1067; }","duration":"376.950041ms","start":"2026-02-19T05:55:31.915484Z","end":"2026-02-19T05:55:32.292434Z","steps":["trace[845967873] 'read index received' (duration: 376.91837ms)","trace[845967873] 'applied index is now lower than readState.Index' (duration: 26.551µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:55:32.29282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.327581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-02-19T05:55:32.292902Z","caller":"traceutil/trace.go:171","msg":"trace[2087808518] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:1; response_revision:1022; }","duration":"377.436595ms","start":"2026-02-19T05:55:31.915445Z","end":"2026-02-19T05:55:32.292882Z","steps":["trace[2087808518] 'agreement among raft nodes before linearized reading' (duration: 377.298071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:32.292959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:55:31.915433Z","time spent":"377.506457ms","remote":"127.0.0.1:51166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":703,"request content":"key:\"/registry/clusterrolebindings/cluster-admin\" "} {"level":"warn","ts":"2026-02-19T05:55:32.292962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.581109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:route-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-02-19T05:55:32.293016Z","caller":"traceutil/trace.go:171","msg":"trace[1973075364] range","detail":"{range_begin:/registry/clusterroles/system:controller:route-controller; range_end:; response_count:1; response_revision:1022; }","duration":"324.710693ms","start":"2026-02-19T05:55:31.968291Z","end":"2026-02-19T05:55:32.293002Z","steps":["trace[1973075364] 'agreement among raft nodes before linearized reading' (duration: 324.496137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:32.292998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.727941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-19T05:55:32.293081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:55:31.96828Z","time spent":"324.787005ms","remote":"127.0.0.1:51158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":701,"request content":"key:\"/registry/clusterroles/system:controller:route-controller\" "} {"level":"info","ts":"2026-02-19T05:55:32.293083Z","caller":"traceutil/trace.go:171","msg":"trace[489548455] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1022; }","duration":"111.842484ms","start":"2026-02-19T05:55:32.181219Z","end":"2026-02-19T05:55:32.293062Z","steps":["trace[489548455] 'agreement among raft nodes before linearized reading' (duration: 111.648498ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:32.855171Z","caller":"traceutil/trace.go:171","msg":"trace[1436527269] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"135.353522ms","start":"2026-02-19T05:55:32.719789Z","end":"2026-02-19T05:55:32.855143Z","steps":["trace[1436527269] 'process raft request' (duration: 40.131675ms)","trace[1436527269] 'compare' (duration: 94.784423ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:55:33.069116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.538739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:55:33.069239Z","caller":"traceutil/trace.go:171","msg":"trace[350233693] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"129.797ms","start":"2026-02-19T05:55:32.93942Z","end":"2026-02-19T05:55:33.069217Z","steps":["trace[350233693] 'read index received' (duration: 22.948412ms)","trace[350233693] 'applied index is now lower than readState.Index' (duration: 106.846978ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:33.069362Z","caller":"traceutil/trace.go:171","msg":"trace[1469645391] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"208.097691ms","start":"2026-02-19T05:55:32.861247Z","end":"2026-02-19T05:55:33.069344Z","steps":["trace[1469645391] 'process raft request' (duration: 101.232762ms)","trace[1469645391] 'compare' (duration: 106.399605ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:55:33.069754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.287513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:990"} {"level":"info","ts":"2026-02-19T05:55:33.070421Z","caller":"traceutil/trace.go:171","msg":"trace[1538193004] range","detail":"{range_begin:/registry/clusterroles/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:1026; }","duration":"118.937132ms","start":"2026-02-19T05:55:32.95142Z","end":"2026-02-19T05:55:33.070358Z","steps":["trace[1538193004] 'agreement among raft nodes before linearized reading' (duration: 118.063547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:33.06977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.337505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-02-19T05:55:33.070518Z","caller":"traceutil/trace.go:171","msg":"trace[67386437] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap; range_end:; response_count:1; response_revision:1026; }","duration":"131.112688ms","start":"2026-02-19T05:55:32.939384Z","end":"2026-02-19T05:55:33.070497Z","steps":["trace[67386437] 'agreement among raft nodes before linearized reading' (duration: 130.293954ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:33.376799Z","caller":"traceutil/trace.go:171","msg":"trace[1152300100] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"288.992934ms","start":"2026-02-19T05:55:33.087793Z","end":"2026-02-19T05:55:33.376786Z","steps":["trace[1152300100] 'process raft request' (duration: 260.562171ms)","trace[1152300100] 'compare' (duration: 28.265298ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:33.376758Z","caller":"traceutil/trace.go:171","msg":"trace[2044758742] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"226.335999ms","start":"2026-02-19T05:55:33.150404Z","end":"2026-02-19T05:55:33.37674Z","steps":["trace[2044758742] 'read index received' (duration: 197.904457ms)","trace[2044758742] 'applied index is now lower than readState.Index' (duration: 28.430602ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:55:33.37708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.581256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" ","response":"range_response_count:1 size:541"} {"level":"warn","ts":"2026-02-19T05:55:33.377202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.381112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:990"} {"level":"info","ts":"2026-02-19T05:55:33.377224Z","caller":"traceutil/trace.go:171","msg":"trace[2030304277] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation; range_end:; response_count:1; response_revision:1027; }","duration":"226.805753ms","start":"2026-02-19T05:55:33.150367Z","end":"2026-02-19T05:55:33.377172Z","steps":["trace[2030304277] 'agreement among raft nodes before linearized reading' (duration: 226.515274ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:33.377241Z","caller":"traceutil/trace.go:171","msg":"trace[478903341] range","detail":"{range_begin:/registry/clusterroles/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:1027; }","duration":"211.442533ms","start":"2026-02-19T05:55:33.165787Z","end":"2026-02-19T05:55:33.377229Z","steps":["trace[478903341] 'agreement among raft nodes before linearized reading' (duration: 211.228047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:34.648768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.370561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:767"} {"level":"info","ts":"2026-02-19T05:55:34.648834Z","caller":"traceutil/trace.go:171","msg":"trace[517065908] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1028; }","duration":"202.479384ms","start":"2026-02-19T05:55:34.446337Z","end":"2026-02-19T05:55:34.648817Z","steps":["trace[517065908] 'range keys from in-memory index tree' (duration: 202.289438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:34.648958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.571502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T05:55:34.648995Z","caller":"traceutil/trace.go:171","msg":"trace[1485904647] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1028; }","duration":"195.635373ms","start":"2026-02-19T05:55:34.453352Z","end":"2026-02-19T05:55:34.648987Z","steps":["trace[1485904647] 'count revisions from in-memory index tree' (duration: 195.52088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:34.649048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.362553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-controller-manager\" ","response":"range_response_count:1 size:1041"} {"level":"info","ts":"2026-02-19T05:55:34.649183Z","caller":"traceutil/trace.go:171","msg":"trace[1999864995] range","detail":"{range_begin:/registry/clusterroles/system:kube-controller-manager; range_end:; response_count:1; response_revision:1028; }","duration":"184.562729ms","start":"2026-02-19T05:55:34.464595Z","end":"2026-02-19T05:55:34.649158Z","steps":["trace[1999864995] 'range keys from in-memory index tree' (duration: 184.160237ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:35.228845Z","caller":"traceutil/trace.go:171","msg":"trace[895811836] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"139.384598ms","start":"2026-02-19T05:55:35.089431Z","end":"2026-02-19T05:55:35.228815Z","steps":["trace[895811836] 'process raft request' (duration: 139.188682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:35.433754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.787611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:monitoring\" ","response":"range_response_count:1 size:634"} {"level":"info","ts":"2026-02-19T05:55:35.433852Z","caller":"traceutil/trace.go:171","msg":"trace[1654953651] range","detail":"{range_begin:/registry/clusterroles/system:monitoring; range_end:; response_count:1; response_revision:1031; }","duration":"121.974257ms","start":"2026-02-19T05:55:35.311855Z","end":"2026-02-19T05:55:35.433829Z","steps":["trace[1654953651] 'range keys from in-memory index tree' (duration: 121.656828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:35.433868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.158321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-02-19T05:55:35.433952Z","caller":"traceutil/trace.go:171","msg":"trace[479119776] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1031; }","duration":"124.286105ms","start":"2026-02-19T05:55:35.309644Z","end":"2026-02-19T05:55:35.43393Z","steps":["trace[479119776] 'range keys from in-memory index tree' (duration: 124.070779ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:36.618484Z","caller":"traceutil/trace.go:171","msg":"trace[1816381298] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"200.175705ms","start":"2026-02-19T05:55:36.418285Z","end":"2026-02-19T05:55:36.618461Z","steps":["trace[1816381298] 'read index received' (duration: 199.960539ms)","trace[1816381298] 'applied index is now lower than readState.Index' (duration: 214.146µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:36.618562Z","caller":"traceutil/trace.go:171","msg":"trace[344260449] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"240.173548ms","start":"2026-02-19T05:55:36.378365Z","end":"2026-02-19T05:55:36.618539Z","steps":["trace[344260449] 'process raft request' (duration: 239.954252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:36.618671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.35356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:1 size:613"} {"level":"warn","ts":"2026-02-19T05:55:36.61869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.409386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-02-19T05:55:36.618718Z","caller":"traceutil/trace.go:171","msg":"trace[819145524] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:1; response_revision:1033; }","duration":"200.428562ms","start":"2026-02-19T05:55:36.418275Z","end":"2026-02-19T05:55:36.618704Z","steps":["trace[819145524] 'agreement among raft nodes before linearized reading' (duration: 200.313269ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:36.618752Z","caller":"traceutil/trace.go:171","msg":"trace[1284988946] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:1033; }","duration":"193.503799ms","start":"2026-02-19T05:55:36.425233Z","end":"2026-02-19T05:55:36.618736Z","steps":["trace[1284988946] 'agreement among raft nodes before linearized reading' (duration: 193.388856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:36.618783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.360916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:55:36.618905Z","caller":"traceutil/trace.go:171","msg":"trace[1698173535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1033; }","duration":"144.522371ms","start":"2026-02-19T05:55:36.47436Z","end":"2026-02-19T05:55:36.618882Z","steps":["trace[1698173535] 'agreement among raft nodes before linearized reading' (duration: 144.309025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:36.861476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.776799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-02-19T05:55:36.861565Z","caller":"traceutil/trace.go:171","msg":"trace[773214041] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:1033; }","duration":"159.875052ms","start":"2026-02-19T05:55:36.70166Z","end":"2026-02-19T05:55:36.861535Z","steps":["trace[773214041] 'range keys from in-memory index tree' (duration: 159.693216ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:37.054762Z","caller":"traceutil/trace.go:171","msg":"trace[677393239] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"115.241393ms","start":"2026-02-19T05:55:36.93949Z","end":"2026-02-19T05:55:37.054731Z","steps":["trace[677393239] 'read index received' (duration: 49.296867ms)","trace[677393239] 'applied index is now lower than readState.Index' (duration: 65.943136ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:37.054801Z","caller":"traceutil/trace.go:171","msg":"trace[1174393205] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"137.571278ms","start":"2026-02-19T05:55:36.917192Z","end":"2026-02-19T05:55:37.054763Z","steps":["trace[1174393205] 'process raft request' (duration: 71.642142ms)","trace[1174393205] 'compare' (duration: 65.72903ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:55:37.240592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.843287ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-19T05:55:37.240648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.137441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-02-19T05:55:37.240708Z","caller":"traceutil/trace.go:171","msg":"trace[559278658] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1035; }","duration":"301.215193ms","start":"2026-02-19T05:55:36.939468Z","end":"2026-02-19T05:55:37.240683Z","steps":["trace[559278658] 'agreement among raft nodes before linearized reading' (duration: 115.390297ms)","trace[559278658] 'range keys from in-memory index tree' (duration: 185.723533ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:55:37.240761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:55:36.939459Z","time spent":"301.281425ms","remote":"127.0.0.1:51158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":725,"request content":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" "} {"level":"info","ts":"2026-02-19T05:55:37.241074Z","caller":"traceutil/trace.go:171","msg":"trace[908022754] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1080; }","duration":"186.221848ms","start":"2026-02-19T05:55:37.054839Z","end":"2026-02-19T05:55:37.241061Z","steps":["trace[908022754] 'read index received' (duration: 47.30664ms)","trace[908022754] 'applied index is now lower than readState.Index' (duration: 138.913988ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:37.241122Z","caller":"traceutil/trace.go:171","msg":"trace[772873275] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"235.728752ms","start":"2026-02-19T05:55:37.00537Z","end":"2026-02-19T05:55:37.241099Z","steps":["trace[772873275] 'process raft request' (duration: 235.30953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:37.43661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.005678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-02-19T05:55:37.436666Z","caller":"traceutil/trace.go:171","msg":"trace[1403551721] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1036; }","duration":"191.104902ms","start":"2026-02-19T05:55:37.245547Z","end":"2026-02-19T05:55:37.436652Z","steps":["trace[1403551721] 'range keys from in-memory index tree' (duration: 190.882145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:37.436852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.561151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-02-19T05:55:37.436979Z","caller":"traceutil/trace.go:171","msg":"trace[1764375642] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:1036; }","duration":"495.707255ms","start":"2026-02-19T05:55:36.941248Z","end":"2026-02-19T05:55:37.436955Z","steps":["trace[1764375642] 'agreement among raft nodes before linearized reading' (duration: 299.859014ms)","trace[1764375642] 'range keys from in-memory index tree' (duration: 195.656715ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:55:37.437051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:55:36.941244Z","time spent":"495.793788ms","remote":"127.0.0.1:51166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":795,"request content":"key:\"/registry/clusterrolebindings/system:controller:endpointslice-controller\" "} {"level":"warn","ts":"2026-02-19T05:55:37.436694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.31394ms","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-19T05:55:37.438294Z","caller":"traceutil/trace.go:171","msg":"trace[1167704543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1036; }","duration":"204.809315ms","start":"2026-02-19T05:55:37.233281Z","end":"2026-02-19T05:55:37.43809Z","steps":["trace[1167704543] 'range keys from in-memory index tree' (duration: 195.260494ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:38.84336Z","caller":"traceutil/trace.go:171","msg":"trace[594061500] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1084; }","duration":"170.235642ms","start":"2026-02-19T05:55:38.673099Z","end":"2026-02-19T05:55:38.843335Z","steps":["trace[594061500] 'read index received' (duration: 170.026556ms)","trace[594061500] 'applied index is now lower than readState.Index' (duration: 207.776µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:38.843487Z","caller":"traceutil/trace.go:171","msg":"trace[1175627288] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"201.855451ms","start":"2026-02-19T05:55:38.641621Z","end":"2026-02-19T05:55:38.843476Z","steps":["trace[1175627288] 'process raft request' (duration: 201.564003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:38.844364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.243782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-02-19T05:55:38.844948Z","caller":"traceutil/trace.go:171","msg":"trace[588821127] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:1039; }","duration":"171.886082ms","start":"2026-02-19T05:55:38.673047Z","end":"2026-02-19T05:55:38.844933Z","steps":["trace[588821127] 'agreement among raft nodes before linearized reading' (duration: 170.614444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:38.845257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.359912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-02-19T05:55:38.846189Z","caller":"traceutil/trace.go:171","msg":"trace[1883274378] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:1039; }","duration":"146.330059ms","start":"2026-02-19T05:55:38.699824Z","end":"2026-02-19T05:55:38.846154Z","steps":["trace[1883274378] 'agreement among raft nodes before linearized reading' (duration: 143.779884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:39.027788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.195648ms","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-19T05:55:39.027865Z","caller":"traceutil/trace.go:171","msg":"trace[1302617629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1039; }","duration":"110.339572ms","start":"2026-02-19T05:55:38.917508Z","end":"2026-02-19T05:55:39.027848Z","steps":["trace[1302617629] 'range keys from in-memory index tree' (duration: 110.014213ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:39.227088Z","caller":"traceutil/trace.go:171","msg":"trace[1683423462] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"129.308902ms","start":"2026-02-19T05:55:39.097754Z","end":"2026-02-19T05:55:39.227063Z","steps":["trace[1683423462] 'read index received' (duration: 31.574488ms)","trace[1683423462] 'applied index is now lower than readState.Index' (duration: 97.733404ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:39.227216Z","caller":"traceutil/trace.go:171","msg":"trace[1508776491] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"140.850441ms","start":"2026-02-19T05:55:39.086351Z","end":"2026-02-19T05:55:39.227201Z","steps":["trace[1508776491] 'process raft request' (duration: 43.025744ms)","trace[1508776491] 'compare' (duration: 97.58537ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:55:39.227346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.415292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-02-19T05:55:39.227428Z","caller":"traceutil/trace.go:171","msg":"trace[1221227981] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:1041; }","duration":"116.537076ms","start":"2026-02-19T05:55:39.110867Z","end":"2026-02-19T05:55:39.227404Z","steps":["trace[1221227981] 'agreement among raft nodes before linearized reading' (duration: 116.385251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:39.227457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.670002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:replication-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-02-19T05:55:39.22773Z","caller":"traceutil/trace.go:171","msg":"trace[1520625213] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:replication-controller; range_end:; response_count:1; response_revision:1041; }","duration":"129.966421ms","start":"2026-02-19T05:55:39.097701Z","end":"2026-02-19T05:55:39.227667Z","steps":["trace[1520625213] 'agreement among raft nodes before linearized reading' (duration: 129.647582ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:41.234613Z","caller":"traceutil/trace.go:171","msg":"trace[794118242] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"140.469484ms","start":"2026-02-19T05:55:41.094118Z","end":"2026-02-19T05:55:41.234588Z","steps":["trace[794118242] 'read index received' (duration: 140.253778ms)","trace[794118242] 'applied index is now lower than readState.Index' (duration: 214.926µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:41.23475Z","caller":"traceutil/trace.go:171","msg":"trace[1569097481] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"145.429701ms","start":"2026-02-19T05:55:41.089306Z","end":"2026-02-19T05:55:41.234736Z","steps":["trace[1569097481] 'process raft request' (duration: 145.122742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:41.235085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.954649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:1 size:363"} {"level":"info","ts":"2026-02-19T05:55:41.235163Z","caller":"traceutil/trace.go:171","msg":"trace[1814650977] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:1; response_revision:1045; }","duration":"141.055422ms","start":"2026-02-19T05:55:41.094092Z","end":"2026-02-19T05:55:41.235147Z","steps":["trace[1814650977] 'agreement among raft nodes before linearized reading' (duration: 140.951319ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:43.051556Z","caller":"traceutil/trace.go:171","msg":"trace[1990050548] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"125.725714ms","start":"2026-02-19T05:55:42.925809Z","end":"2026-02-19T05:55:43.051534Z","steps":["trace[1990050548] 'read index received' (duration: 125.57171ms)","trace[1990050548] 'applied index is now lower than readState.Index' (duration: 153.164µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:43.051638Z","caller":"traceutil/trace.go:171","msg":"trace[1315420937] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"186.631447ms","start":"2026-02-19T05:55:42.864968Z","end":"2026-02-19T05:55:43.0516Z","steps":["trace[1315420937] 'process raft request' (duration: 186.433251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:43.051768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.189446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/default/\" range_end:\"/registry/daemonsets/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:55:43.051807Z","caller":"traceutil/trace.go:171","msg":"trace[195124475] range","detail":"{range_begin:/registry/daemonsets/default/; range_end:/registry/daemonsets/default0; response_count:0; response_revision:1051; }","duration":"108.2833ms","start":"2026-02-19T05:55:42.943513Z","end":"2026-02-19T05:55:43.051797Z","steps":["trace[195124475] 'agreement among raft nodes before linearized reading' (duration: 108.177467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:55:43.052005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.175057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/default/kube-root-ca.crt\" ","response":"range_response_count:1 size:1738"} {"level":"info","ts":"2026-02-19T05:55:43.052095Z","caller":"traceutil/trace.go:171","msg":"trace[1078405186] range","detail":"{range_begin:/registry/configmaps/default/kube-root-ca.crt; range_end:; response_count:1; response_revision:1051; }","duration":"126.333603ms","start":"2026-02-19T05:55:42.925742Z","end":"2026-02-19T05:55:43.052075Z","steps":["trace[1078405186] 'agreement among raft nodes before linearized reading' (duration: 125.886439ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:45.191572Z","caller":"traceutil/trace.go:171","msg":"trace[339598567] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"124.38665ms","start":"2026-02-19T05:55:45.067152Z","end":"2026-02-19T05:55:45.191538Z","steps":["trace[339598567] 'read index received' (duration: 124.202575ms)","trace[339598567] 'applied index is now lower than readState.Index' (duration: 182.965µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:55:45.191765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.590676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/\" range_end:\"/registry/configmaps/kube-system0\" limit:500 ","response":"range_response_count:8 size:16534"} {"level":"info","ts":"2026-02-19T05:55:45.191799Z","caller":"traceutil/trace.go:171","msg":"trace[1868061323] range","detail":"{range_begin:/registry/configmaps/kube-system/; range_end:/registry/configmaps/kube-system0; response_count:8; response_revision:1056; }","duration":"124.692368ms","start":"2026-02-19T05:55:45.067095Z","end":"2026-02-19T05:55:45.191787Z","steps":["trace[1868061323] 'agreement among raft nodes before linearized reading' (duration: 124.537534ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:45.192148Z","caller":"traceutil/trace.go:171","msg":"trace[1847673272] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"132.547301ms","start":"2026-02-19T05:55:45.059591Z","end":"2026-02-19T05:55:45.192139Z","steps":["trace[1847673272] 'process raft request' (duration: 131.84375ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:47.793424Z","caller":"traceutil/trace.go:171","msg":"trace[579669926] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"106.095494ms","start":"2026-02-19T05:55:47.687297Z","end":"2026-02-19T05:55:47.793392Z","steps":["trace[579669926] 'read index received' (duration: 47.701109ms)","trace[579669926] 'applied index is now lower than readState.Index' (duration: 58.392945ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:55:47.793503Z","caller":"traceutil/trace.go:171","msg":"trace[2139491365] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"113.291326ms","start":"2026-02-19T05:55:47.680179Z","end":"2026-02-19T05:55:47.79347Z","steps":["trace[2139491365] 'process raft request' (duration: 54.783397ms)","trace[2139491365] 'compare' (duration: 58.265121ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:55:47.793646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.318741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7036"} {"level":"info","ts":"2026-02-19T05:55:47.793734Z","caller":"traceutil/trace.go:171","msg":"trace[990377278] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1067; }","duration":"106.439234ms","start":"2026-02-19T05:55:47.687277Z","end":"2026-02-19T05:55:47.793716Z","steps":["trace[990377278] 'agreement among raft nodes before linearized reading' (duration: 106.261009ms)"],"step_count":1}