{"level":"warn","ts":"2026-02-25T01:40:37.590009Z","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-25T01:40:37.590095Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-25T01:40:37.590164Z","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-25T01:40:37.590176Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-02-25T01:40:37.590195Z","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-25T01:40:37.590889Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-02-25T01:40:37.590963Z","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://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-25T01:40:37.594991Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.826107ms"} {"level":"info","ts":"2026-02-25T01:40:37.599857Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-02-25T01:40:37.599932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T01:40:37.599958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-02-25T01:40:37.599973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T01:40:37.599988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-02-25T01:40:37.600029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-02-25T01:40:37.608531Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T01:40:37.610072Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T01:40:37.611363Z","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-25T01:40:37.614566Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T01:40:37.617917Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T01:40:37.618035Z","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-25T01:40:37.618143Z","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-25T01:40:37.61817Z","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-25T01:40:37.618363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-02-25T01:40:37.620521Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-02-25T01:40:37.621431Z","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-25T01:40:37.621521Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-02-25T01:40:37.621547Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-02-25T01:40:37.621707Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T01:40:37.621756Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T01:40:37.700832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T01:40:37.700888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T01:40:37.700903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-02-25T01:40:37.700913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-02-25T01:40:37.700918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-02-25T01:40:37.700978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-02-25T01:40:37.700989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-02-25T01:40:37.846691Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:40:37.896526Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T01:40:37.896723Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:40:37.896764Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:40:37.897091Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T01:40:37.897138Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T01:40:37.900406Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-02-25T01:40:37.900642Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T01:40:37.916754Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:40:37.916913Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:40:37.91698Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-25T01:40:45.332209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.847026155s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:40:45.332543Z","caller":"traceutil/trace.go:171","msg":"trace[1800127992] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"2.307359611s","start":"2026-02-25T01:40:43.02517Z","end":"2026-02-25T01:40:45.33253Z","steps":["trace[1800127992] 'process raft request' (duration: 2.307253258s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:45.332565Z","caller":"traceutil/trace.go:171","msg":"trace[1556341804] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"2.317941026s","start":"2026-02-25T01:40:43.014605Z","end":"2026-02-25T01:40:45.332546Z","steps":["trace[1556341804] 'process raft request' (duration: 469.57699ms)","trace[1556341804] 'compare' (duration: 1.846085135s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:45.332638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:43.025158Z","time spent":"2.307423514s","remote":"127.0.0.1:34990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1934,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:40:45.332712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:43.014585Z","time spent":"2.318053199s","remote":"127.0.0.1:34964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:40:45.332509Z","caller":"traceutil/trace.go:171","msg":"trace[70765362] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"2.316305179s","start":"2026-02-25T01:40:43.016176Z","end":"2026-02-25T01:40:45.332481Z","steps":["trace[70765362] 'process raft request' (duration: 2.316149545s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:45.332474Z","caller":"traceutil/trace.go:171","msg":"trace[134607581] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:248; }","duration":"2.307221097s","start":"2026-02-25T01:40:43.025237Z","end":"2026-02-25T01:40:45.332458Z","steps":["trace[134607581] 'read index received' (duration: 458.955193ms)","trace[134607581] 'applied index is now lower than readState.Index' (duration: 1.848264924s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:40:45.333089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.307841806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:40:45.333133Z","caller":"traceutil/trace.go:171","msg":"trace[648167085] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:244; }","duration":"2.307905888s","start":"2026-02-25T01:40:43.025204Z","end":"2026-02-25T01:40:45.33311Z","steps":["trace[648167085] 'agreement among raft nodes before linearized reading' (duration: 2.307846476s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:45.333164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:43.025194Z","time spent":"2.30796235s","remote":"127.0.0.1:35086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" "} {"level":"warn","ts":"2026-02-25T01:40:45.33324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:43.016119Z","time spent":"2.316879309s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:40:45.391623Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.058023136s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T01:40:45.392077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247422924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T01:40:45.392081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.650449823s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:40:45.392096Z","caller":"traceutil/trace.go:171","msg":"trace[649405541] transaction","detail":"{read_only:false; number_of_response:0; response_revision:244; }","duration":"1.558850026s","start":"2026-02-25T01:40:43.833217Z","end":"2026-02-25T01:40:45.392067Z","steps":["trace[649405541] 'process raft request' (duration: 1.55866864s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:45.392171Z","caller":"traceutil/trace.go:171","msg":"trace[1739803910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:244; }","duration":"1.650566227s","start":"2026-02-25T01:40:43.741581Z","end":"2026-02-25T01:40:45.392147Z","steps":["trace[1739803910] 'agreement among raft nodes before linearized reading' (duration: 1.650416222s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:45.392163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.859202629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:40:45.392165Z","caller":"traceutil/trace.go:171","msg":"trace[2047148561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:244; }","duration":"1.247556649s","start":"2026-02-25T01:40:44.144587Z","end":"2026-02-25T01:40:45.392144Z","steps":["trace[2047148561] 'agreement among raft nodes before linearized reading' (duration: 1.247389473s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:45.39222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:43.74153Z","time spent":"1.650680801s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T01:40:45.392232Z","caller":"traceutil/trace.go:171","msg":"trace[220135515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:244; }","duration":"1.859373124s","start":"2026-02-25T01:40:43.532846Z","end":"2026-02-25T01:40:45.392219Z","steps":["trace[220135515] 'agreement among raft nodes before linearized reading' (duration: 1.859210099s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:45.39224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:44.144569Z","time spent":"1.247660561s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:40:45.392267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:43.532791Z","time spent":"1.859467717s","remote":"127.0.0.1:34878","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-25T01:40:45.392356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:43.833197Z","time spent":"1.558979161s","remote":"127.0.0.1:35074","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:40:45.392475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.86584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T01:40:45.392998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561162379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-25T01:40:45.392611Z","caller":"traceutil/trace.go:171","msg":"trace[1200789936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:244; }","duration":"606.080467ms","start":"2026-02-25T01:40:44.786495Z","end":"2026-02-25T01:40:45.392575Z","steps":["trace[1200789936] 'agreement among raft nodes before linearized reading' (duration: 605.610042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:45.39349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:44.786475Z","time spent":"606.950394ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:40:45.39343Z","caller":"traceutil/trace.go:171","msg":"trace[937820632] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:244; }","duration":"1.561323385s","start":"2026-02-25T01:40:43.831746Z","end":"2026-02-25T01:40:45.393069Z","steps":["trace[937820632] 'agreement among raft nodes before linearized reading' (duration: 1.561115518s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:45.393586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:43.831719Z","time spent":"1.561845752s","remote":"127.0.0.1:35074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3468,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-02-25T01:40:45.760975Z","caller":"traceutil/trace.go:171","msg":"trace[1823079906] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:252; }","duration":"368.988675ms","start":"2026-02-25T01:40:45.391963Z","end":"2026-02-25T01:40:45.760951Z","steps":["trace[1823079906] 'read index received' (duration: 366.119475ms)","trace[1823079906] 'applied index is now lower than readState.Index' (duration: 2.86839ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:45.761077Z","caller":"traceutil/trace.go:171","msg":"trace[735929504] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"421.408257ms","start":"2026-02-25T01:40:45.339646Z","end":"2026-02-25T01:40:45.761054Z","steps":["trace[735929504] 'process raft request' (duration: 418.183666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:45.761278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:45.339629Z","time spent":"421.586163ms","remote":"127.0.0.1:35342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2629,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:40:45.761312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.679692ms","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-25T01:40:45.761414Z","caller":"traceutil/trace.go:171","msg":"trace[934145279] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:245; }","duration":"425.835446ms","start":"2026-02-25T01:40:45.335567Z","end":"2026-02-25T01:40:45.761403Z","steps":["trace[934145279] 'agreement among raft nodes before linearized reading' (duration: 425.61857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:45.761521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:45.33555Z","time spent":"425.962291ms","remote":"127.0.0.1:35006","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":"info","ts":"2026-02-25T01:40:45.76323Z","caller":"traceutil/trace.go:171","msg":"trace[1093750918] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"364.784503ms","start":"2026-02-25T01:40:45.398424Z","end":"2026-02-25T01:40:45.763209Z","steps":["trace[1093750918] 'process raft request' (duration: 364.558716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:45.763404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:45.398401Z","time spent":"364.941367ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:40:45.763554Z","caller":"traceutil/trace.go:171","msg":"trace[313058400] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"364.874926ms","start":"2026-02-25T01:40:45.398661Z","end":"2026-02-25T01:40:45.763536Z","steps":["trace[313058400] 'process raft request' (duration: 364.424031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:45.763653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:45.398652Z","time spent":"364.956858ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:40:45.763568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.707847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5875"} {"level":"info","ts":"2026-02-25T01:40:45.76378Z","caller":"traceutil/trace.go:171","msg":"trace[1872367882] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:249; }","duration":"369.975606ms","start":"2026-02-25T01:40:45.393793Z","end":"2026-02-25T01:40:45.763769Z","steps":["trace[1872367882] 'agreement among raft nodes before linearized reading' (duration: 369.602864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:45.763838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:45.393775Z","time spent":"370.053539ms","remote":"127.0.0.1:35074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5899,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-02-25T01:40:45.763984Z","caller":"traceutil/trace.go:171","msg":"trace[371956200] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"365.219126ms","start":"2026-02-25T01:40:45.398683Z","end":"2026-02-25T01:40:45.763902Z","steps":["trace[371956200] 'process raft request' (duration: 364.445692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:45.764078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:45.39866Z","time spent":"365.369781ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:40:45.764223Z","caller":"traceutil/trace.go:171","msg":"trace[990634485] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"354.725076ms","start":"2026-02-25T01:40:45.409487Z","end":"2026-02-25T01:40:45.764212Z","steps":["trace[990634485] 'process raft request' (duration: 353.678583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:45.764286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:40:45.409449Z","time spent":"354.803998ms","remote":"127.0.0.1:35074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4583,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:40:56.564791Z","caller":"traceutil/trace.go:171","msg":"trace[1752593567] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"205.108642ms","start":"2026-02-25T01:40:56.359658Z","end":"2026-02-25T01:40:56.564767Z","steps":["trace[1752593567] 'process raft request' (duration: 204.770471ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:56.564861Z","caller":"traceutil/trace.go:171","msg":"trace[2040107744] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"203.853073ms","start":"2026-02-25T01:40:56.360978Z","end":"2026-02-25T01:40:56.564831Z","steps":["trace[2040107744] 'process raft request' (duration: 203.492391ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:56.564854Z","caller":"traceutil/trace.go:171","msg":"trace[581459463] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"206.491015ms","start":"2026-02-25T01:40:56.358323Z","end":"2026-02-25T01:40:56.564814Z","steps":["trace[581459463] 'process raft request' (duration: 206.051271ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:56.565037Z","caller":"traceutil/trace.go:171","msg":"trace[1474089630] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"119.199326ms","start":"2026-02-25T01:40:56.445799Z","end":"2026-02-25T01:40:56.564998Z","steps":["trace[1474089630] 'process raft request' (duration: 118.726591ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:56.56507Z","caller":"traceutil/trace.go:171","msg":"trace[1540560638] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"206.712933ms","start":"2026-02-25T01:40:56.358282Z","end":"2026-02-25T01:40:56.564995Z","steps":["trace[1540560638] 'process raft request' (duration: 156.854202ms)","trace[1540560638] 'compare' (duration: 49.096667ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:56.796658Z","caller":"traceutil/trace.go:171","msg":"trace[1979671810] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"138.285917ms","start":"2026-02-25T01:40:56.65835Z","end":"2026-02-25T01:40:56.796636Z","steps":["trace[1979671810] 'process raft request' (duration: 134.145806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:10.033805Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184552258298897,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:41:10.166851Z","caller":"traceutil/trace.go:171","msg":"trace[1820701294] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"634.010955ms","start":"2026-02-25T01:41:09.53281Z","end":"2026-02-25T01:41:10.166821Z","steps":["trace[1820701294] 'read index received' (duration: 633.766567ms)","trace[1820701294] 'applied index is now lower than readState.Index' (duration: 242.978µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:10.166911Z","caller":"traceutil/trace.go:171","msg":"trace[1272425466] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"728.912815ms","start":"2026-02-25T01:41:09.437963Z","end":"2026-02-25T01:41:10.166876Z","steps":["trace[1272425466] 'process raft request' (duration: 728.700848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:10.167055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.656864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4066"} {"level":"warn","ts":"2026-02-25T01:41:10.167073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:09.437938Z","time spent":"729.039689ms","remote":"127.0.0.1:35126","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-25T01:41:10.167103Z","caller":"traceutil/trace.go:171","msg":"trace[1213047991] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:467; }","duration":"348.768799ms","start":"2026-02-25T01:41:09.818321Z","end":"2026-02-25T01:41:10.167089Z","steps":["trace[1213047991] 'agreement among raft nodes before linearized reading' (duration: 348.666025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:10.167144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:09.818299Z","time spent":"348.83606ms","remote":"127.0.0.1:35070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4090,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T01:41:10.167159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.363517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:41:10.167244Z","caller":"traceutil/trace.go:171","msg":"trace[1015736841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"250.463551ms","start":"2026-02-25T01:41:09.91676Z","end":"2026-02-25T01:41:10.167224Z","steps":["trace[1015736841] 'agreement among raft nodes before linearized reading' (duration: 250.321616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:10.167272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.010371ms","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-25T01:41:10.167365Z","caller":"traceutil/trace.go:171","msg":"trace[1046136598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"253.160256ms","start":"2026-02-25T01:41:09.914177Z","end":"2026-02-25T01:41:10.167338Z","steps":["trace[1046136598] 'agreement among raft nodes before linearized reading' (duration: 252.939859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:10.167576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.498354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:41:10.167612Z","caller":"traceutil/trace.go:171","msg":"trace[388201218] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:467; }","duration":"251.582206ms","start":"2026-02-25T01:41:09.916019Z","end":"2026-02-25T01:41:10.167602Z","steps":["trace[388201218] 'agreement among raft nodes before linearized reading' (duration: 251.518004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:10.167716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.233112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:10.167837Z","caller":"traceutil/trace.go:171","msg":"trace[602129228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:467; }","duration":"635.060597ms","start":"2026-02-25T01:41:09.532757Z","end":"2026-02-25T01:41:10.167818Z","steps":["trace[602129228] 'agreement among raft nodes before linearized reading' (duration: 634.208161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:10.167886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:09.532736Z","time spent":"635.13654ms","remote":"127.0.0.1:34890","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-25T01:41:10.984113Z","caller":"traceutil/trace.go:171","msg":"trace[527888947] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"810.269608ms","start":"2026-02-25T01:41:10.173821Z","end":"2026-02-25T01:41:10.984091Z","steps":["trace[527888947] 'process raft request' (duration: 810.110983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:10.98424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:10.173768Z","time spent":"810.408633ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:10.984519Z","caller":"traceutil/trace.go:171","msg":"trace[1659853283] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"810.66023ms","start":"2026-02-25T01:41:10.17385Z","end":"2026-02-25T01:41:10.98451Z","steps":["trace[1659853283] 'process raft request' (duration: 810.213116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:10.984579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:10.173829Z","time spent":"810.722462ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:10.984592Z","caller":"traceutil/trace.go:171","msg":"trace[475703478] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"808.719129ms","start":"2026-02-25T01:41:10.175849Z","end":"2026-02-25T01:41:10.984568Z","steps":["trace[475703478] 'process raft request' (duration: 808.495422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:10.984817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:10.175841Z","time spent":"808.898695ms","remote":"127.0.0.1:35070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4258,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:11.383492Z","caller":"traceutil/trace.go:171","msg":"trace[1166727951] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:483; }","duration":"848.938377ms","start":"2026-02-25T01:41:10.534505Z","end":"2026-02-25T01:41:11.383444Z","steps":["trace[1166727951] 'read index received' (duration: 449.42581ms)","trace[1166727951] 'applied index is now lower than readState.Index' (duration: 399.511657ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:11.38375Z","caller":"traceutil/trace.go:171","msg":"trace[1958611519] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"975.117042ms","start":"2026-02-25T01:41:10.408619Z","end":"2026-02-25T01:41:11.383736Z","steps":["trace[1958611519] 'process raft request' (duration: 943.01177ms)","trace[1958611519] 'compare' (duration: 31.713ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:11.383818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.288728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:41:11.383862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:10.40859Z","time spent":"975.197705ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:11.383902Z","caller":"traceutil/trace.go:171","msg":"trace[1858773653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"849.403582ms","start":"2026-02-25T01:41:10.534476Z","end":"2026-02-25T01:41:11.38388Z","steps":["trace[1858773653] 'agreement among raft nodes before linearized reading' (duration: 849.262437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:11.383948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:10.534461Z","time spent":"849.477913ms","remote":"127.0.0.1:34890","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-25T01:41:11.384282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.111803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T01:41:11.384493Z","caller":"traceutil/trace.go:171","msg":"trace[1659170624] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:472; }","duration":"385.356691ms","start":"2026-02-25T01:41:10.999116Z","end":"2026-02-25T01:41:11.384472Z","steps":["trace[1659170624] 'agreement among raft nodes before linearized reading' (duration: 384.618078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:11.384593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:10.9991Z","time spent":"385.480155ms","remote":"127.0.0.1:34902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.250\" "} {"level":"info","ts":"2026-02-25T01:41:18.380629Z","caller":"traceutil/trace.go:171","msg":"trace[921347017] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"151.936987ms","start":"2026-02-25T01:41:18.228664Z","end":"2026-02-25T01:41:18.380601Z","steps":["trace[921347017] 'read index received' (duration: 151.593026ms)","trace[921347017] 'applied index is now lower than readState.Index' (duration: 342.881µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:18.380702Z","caller":"traceutil/trace.go:171","msg":"trace[562210789] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"448.658435ms","start":"2026-02-25T01:41:17.932019Z","end":"2026-02-25T01:41:18.380677Z","steps":["trace[562210789] 'process raft request' (duration: 448.327955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:18.380863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.182785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-25T01:41:18.380913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:17.931989Z","time spent":"448.837251ms","remote":"127.0.0.1:35074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:18.380977Z","caller":"traceutil/trace.go:171","msg":"trace[1896479955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:490; }","duration":"152.35445ms","start":"2026-02-25T01:41:18.228602Z","end":"2026-02-25T01:41:18.380956Z","steps":["trace[1896479955] 'agreement among raft nodes before linearized reading' (duration: 152.104952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:18.381384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.997587ms","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-25T01:41:18.381436Z","caller":"traceutil/trace.go:171","msg":"trace[296338589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:490; }","duration":"144.068919ms","start":"2026-02-25T01:41:18.237351Z","end":"2026-02-25T01:41:18.38142Z","steps":["trace[296338589] 'agreement among raft nodes before linearized reading' (duration: 143.976486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:20.173311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326587594s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:41:20.173413Z","caller":"traceutil/trace.go:171","msg":"trace[1940915021] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"1.640164273s","start":"2026-02-25T01:41:18.533232Z","end":"2026-02-25T01:41:20.173396Z","steps":["trace[1940915021] 'read index received' (duration: 313.351082ms)","trace[1940915021] 'applied index is now lower than readState.Index' (duration: 1.326811811s)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:20.173535Z","caller":"traceutil/trace.go:171","msg":"trace[1504219936] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.786159843s","start":"2026-02-25T01:41:18.387364Z","end":"2026-02-25T01:41:20.173524Z","steps":["trace[1504219936] 'process raft request' (duration: 459.27546ms)","trace[1504219936] 'compare' (duration: 1.326383917s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:20.173594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:18.38734Z","time spent":"1.786221825s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:20.1738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.640571507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:20.173832Z","caller":"traceutil/trace.go:171","msg":"trace[1862037666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"1.640712851s","start":"2026-02-25T01:41:18.533109Z","end":"2026-02-25T01:41:20.173822Z","steps":["trace[1862037666] 'agreement among raft nodes before linearized reading' (duration: 1.640658179s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:20.173861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:18.533091Z","time spent":"1.640763763s","remote":"127.0.0.1:34890","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-25T01:41:20.174066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161206753s","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-25T01:41:20.174096Z","caller":"traceutil/trace.go:171","msg":"trace[619237835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"1.161269825s","start":"2026-02-25T01:41:19.012817Z","end":"2026-02-25T01:41:20.174087Z","steps":["trace[619237835] 'agreement among raft nodes before linearized reading' (duration: 1.161158171s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:20.174126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:19.012798Z","time spent":"1.161321517s","remote":"127.0.0.1:35126","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-25T01:41:20.174562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160055607s","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-25T01:41:20.174742Z","caller":"traceutil/trace.go:171","msg":"trace[1748883728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"1.160274504s","start":"2026-02-25T01:41:19.014439Z","end":"2026-02-25T01:41:20.174713Z","steps":["trace[1748883728] 'agreement among raft nodes before linearized reading' (duration: 1.159917242s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:20.174769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.758318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:41:20.174851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:19.014411Z","time spent":"1.160405208s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T01:41:20.174871Z","caller":"traceutil/trace.go:171","msg":"trace[641592721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"169.904273ms","start":"2026-02-25T01:41:20.004944Z","end":"2026-02-25T01:41:20.174849Z","steps":["trace[641592721] 'agreement among raft nodes before linearized reading' (duration: 169.754428ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:20.472801Z","caller":"traceutil/trace.go:171","msg":"trace[1397172886] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"292.841046ms","start":"2026-02-25T01:41:20.179934Z","end":"2026-02-25T01:41:20.472775Z","steps":["trace[1397172886] 'process raft request' (duration: 232.96408ms)","trace[1397172886] 'compare' (duration: 59.779493ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:20.473216Z","caller":"traceutil/trace.go:171","msg":"trace[1688374375] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"292.392012ms","start":"2026-02-25T01:41:20.180801Z","end":"2026-02-25T01:41:20.473193Z","steps":["trace[1688374375] 'process raft request' (duration: 292.301659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:22.569046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.257716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-02-25T01:41:22.569068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.257855ms","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-25T01:41:22.569124Z","caller":"traceutil/trace.go:171","msg":"trace[635046954] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:508; }","duration":"437.383ms","start":"2026-02-25T01:41:22.131723Z","end":"2026-02-25T01:41:22.569106Z","steps":["trace[635046954] 'range keys from in-memory index tree' (duration: 437.146982ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:22.56914Z","caller":"traceutil/trace.go:171","msg":"trace[849543658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:508; }","duration":"390.360918ms","start":"2026-02-25T01:41:22.178761Z","end":"2026-02-25T01:41:22.569122Z","steps":["trace[849543658] 'range keys from in-memory index tree' (duration: 390.08488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:22.56918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:22.178749Z","time spent":"390.4218ms","remote":"127.0.0.1:35126","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-25T01:41:22.569182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:22.131697Z","time spent":"437.472513ms","remote":"127.0.0.1:35006","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":"info","ts":"2026-02-25T01:41:28.73882Z","caller":"traceutil/trace.go:171","msg":"trace[614364446] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"121.939802ms","start":"2026-02-25T01:41:28.616849Z","end":"2026-02-25T01:41:28.738789Z","steps":["trace[614364446] 'process raft request' (duration: 121.827719ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:28.738825Z","caller":"traceutil/trace.go:171","msg":"trace[928532010] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"125.085735ms","start":"2026-02-25T01:41:28.613697Z","end":"2026-02-25T01:41:28.738782Z","steps":["trace[928532010] 'process raft request' (duration: 121.86988ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:28.738888Z","caller":"traceutil/trace.go:171","msg":"trace[720206660] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"120.145134ms","start":"2026-02-25T01:41:28.618694Z","end":"2026-02-25T01:41:28.738839Z","steps":["trace[720206660] 'read index received' (duration: 116.852317ms)","trace[720206660] 'applied index is now lower than readState.Index' (duration: 3.291467ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:28.74208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.878231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:41:28.742216Z","caller":"traceutil/trace.go:171","msg":"trace[1789394812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:600; }","duration":"122.050006ms","start":"2026-02-25T01:41:28.620108Z","end":"2026-02-25T01:41:28.742158Z","steps":["trace[1789394812] 'agreement among raft nodes before linearized reading' (duration: 119.275436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:28.742386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.664999ms","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-25T01:41:28.742522Z","caller":"traceutil/trace.go:171","msg":"trace[225466288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"123.786733ms","start":"2026-02-25T01:41:28.618672Z","end":"2026-02-25T01:41:28.742459Z","steps":["trace[225466288] 'agreement among raft nodes before linearized reading' (duration: 120.265968ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:35.11904Z","caller":"traceutil/trace.go:171","msg":"trace[1187114491] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:686; }","duration":"104.12399ms","start":"2026-02-25T01:41:35.014856Z","end":"2026-02-25T01:41:35.11898Z","steps":["trace[1187114491] 'read index received' (duration: 103.364625ms)","trace[1187114491] 'applied index is now lower than readState.Index' (duration: 757.615µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:35.119314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.42503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-tvttn\" ","response":"range_response_count:1 size:4818"} {"level":"info","ts":"2026-02-25T01:41:35.119299Z","caller":"traceutil/trace.go:171","msg":"trace[666707095] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"269.644706ms","start":"2026-02-25T01:41:34.849616Z","end":"2026-02-25T01:41:35.119261Z","steps":["trace[666707095] 'process raft request' (duration: 268.5177ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:35.119397Z","caller":"traceutil/trace.go:171","msg":"trace[768183514] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"269.727479ms","start":"2026-02-25T01:41:34.84966Z","end":"2026-02-25T01:41:35.119387Z","steps":["trace[768183514] 'process raft request' (duration: 269.261514ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:35.119354Z","caller":"traceutil/trace.go:171","msg":"trace[2049218795] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-tvttn; range_end:; response_count:1; response_revision:666; }","duration":"104.525544ms","start":"2026-02-25T01:41:35.014815Z","end":"2026-02-25T01:41:35.11934Z","steps":["trace[2049218795] 'agreement among raft nodes before linearized reading' (duration: 104.306057ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:35.165769Z","caller":"traceutil/trace.go:171","msg":"trace[2040090168] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"148.17104ms","start":"2026-02-25T01:41:35.017575Z","end":"2026-02-25T01:41:35.165746Z","steps":["trace[2040090168] 'process raft request' (duration: 148.042215ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:36.38299Z","caller":"traceutil/trace.go:171","msg":"trace[2146619776] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"230.805068ms","start":"2026-02-25T01:41:36.152155Z","end":"2026-02-25T01:41:36.38296Z","steps":["trace[2146619776] 'process raft request' (duration: 230.632673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:36.655241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.580354ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T01:41:36.703275Z","caller":"traceutil/trace.go:171","msg":"trace[17989220] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"304.086471ms","start":"2026-02-25T01:41:36.399166Z","end":"2026-02-25T01:41:36.703252Z","steps":["trace[17989220] 'process raft request' (duration: 304.003289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:36.703507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:36.399129Z","time spent":"304.299569ms","remote":"127.0.0.1:35342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:36.70331Z","caller":"traceutil/trace.go:171","msg":"trace[51084000] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"309.433826ms","start":"2026-02-25T01:41:36.393842Z","end":"2026-02-25T01:41:36.703276Z","steps":["trace[51084000] 'process raft request' (duration: 309.184318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:36.703722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:36.393819Z","time spent":"309.870451ms","remote":"127.0.0.1:35070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4088,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:36.704071Z","caller":"traceutil/trace.go:171","msg":"trace[700380251] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:693; }","duration":"170.388965ms","start":"2026-02-25T01:41:36.533658Z","end":"2026-02-25T01:41:36.704047Z","steps":["trace[700380251] 'read index received' (duration: 170.362304ms)","trace[700380251] 'applied index is now lower than readState.Index' (duration: 7.591µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:36.704376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.690545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:36.704572Z","caller":"traceutil/trace.go:171","msg":"trace[42966756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:670; }","duration":"170.939883ms","start":"2026-02-25T01:41:36.533618Z","end":"2026-02-25T01:41:36.704558Z","steps":["trace[42966756] 'agreement among raft nodes before linearized reading' (duration: 170.505489ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:36.982732Z","caller":"traceutil/trace.go:171","msg":"trace[825861065] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"129.624634ms","start":"2026-02-25T01:41:36.853077Z","end":"2026-02-25T01:41:36.982702Z","steps":["trace[825861065] 'process raft request' (duration: 129.364306ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:39.318788Z","caller":"traceutil/trace.go:171","msg":"trace[822349036] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"319.95187ms","start":"2026-02-25T01:41:38.998813Z","end":"2026-02-25T01:41:39.318765Z","steps":["trace[822349036] 'read index received' (duration: 319.753674ms)","trace[822349036] 'applied index is now lower than readState.Index' (duration: 197.356µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:39.318912Z","caller":"traceutil/trace.go:171","msg":"trace[1871511567] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"327.666232ms","start":"2026-02-25T01:41:38.991236Z","end":"2026-02-25T01:41:39.318902Z","steps":["trace[1871511567] 'process raft request' (duration: 327.381962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.319029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.99122Z","time spent":"327.728504ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:39.319104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.053579ms","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-25T01:41:39.319162Z","caller":"traceutil/trace.go:171","msg":"trace[270974907] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:679; }","duration":"127.155303ms","start":"2026-02-25T01:41:39.191993Z","end":"2026-02-25T01:41:39.319148Z","steps":["trace[270974907] 'agreement among raft nodes before linearized reading' (duration: 127.012038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.319319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.891084ms","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-25T01:41:39.319381Z","caller":"traceutil/trace.go:171","msg":"trace[44476533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:679; }","duration":"126.995808ms","start":"2026-02-25T01:41:39.19237Z","end":"2026-02-25T01:41:39.319366Z","steps":["trace[44476533] 'agreement among raft nodes before linearized reading' (duration: 126.821392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.319318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.477317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:41:39.319474Z","caller":"traceutil/trace.go:171","msg":"trace[1454862701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:679; }","duration":"320.671153ms","start":"2026-02-25T01:41:38.998796Z","end":"2026-02-25T01:41:39.319467Z","steps":["trace[1454862701] 'agreement among raft nodes before linearized reading' (duration: 320.373323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.319494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.998787Z","time spent":"320.700295ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:41:40.506338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184552258299312,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:41:40.564549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.421429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:41:40.564702Z","caller":"traceutil/trace.go:171","msg":"trace[1982262447] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:702; }","duration":"558.623315ms","start":"2026-02-25T01:41:40.006067Z","end":"2026-02-25T01:41:40.564691Z","steps":["trace[1982262447] 'read index received' (duration: 534.816698ms)","trace[1982262447] 'applied index is now lower than readState.Index' (duration: 23.805627ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:40.564768Z","caller":"traceutil/trace.go:171","msg":"trace[51579106] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.242057316s","start":"2026-02-25T01:41:39.322701Z","end":"2026-02-25T01:41:40.564758Z","steps":["trace[51579106] 'process raft request' (duration: 1.241928741s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:40.564809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.322688Z","time spent":"1.242095917s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:40.564812Z","caller":"traceutil/trace.go:171","msg":"trace[1768057527] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"1.242132619s","start":"2026-02-25T01:41:39.322665Z","end":"2026-02-25T01:41:40.564797Z","steps":["trace[1768057527] 'process raft request' (duration: 596.389659ms)","trace[1768057527] 'compare' (duration: 645.226413ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:40.564876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.322646Z","time spent":"1.24219547s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:40.565083Z","caller":"traceutil/trace.go:171","msg":"trace[723984981] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.242291144s","start":"2026-02-25T01:41:39.322782Z","end":"2026-02-25T01:41:40.565073Z","steps":["trace[723984981] 'process raft request' (duration: 1.241884901s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:40.565135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.322765Z","time spent":"1.242340225s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:40.56521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.140912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:40.56526Z","caller":"traceutil/trace.go:171","msg":"trace[1926204374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"559.219664ms","start":"2026-02-25T01:41:40.006024Z","end":"2026-02-25T01:41:40.565243Z","steps":["trace[1926204374] 'agreement among raft nodes before linearized reading' (duration: 559.138242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:40.56529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:40.006005Z","time spent":"559.278577ms","remote":"127.0.0.1:34890","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-25T01:41:42.388811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530738204s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:42.388928Z","caller":"traceutil/trace.go:171","msg":"trace[404747025] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:682; }","duration":"1.530877648s","start":"2026-02-25T01:41:40.858034Z","end":"2026-02-25T01:41:42.388912Z","steps":["trace[404747025] 'range keys from in-memory index tree' (duration: 1.530707253s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:42.388925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.031273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:42.388999Z","caller":"traceutil/trace.go:171","msg":"trace[1183799967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"858.175948ms","start":"2026-02-25T01:41:41.530802Z","end":"2026-02-25T01:41:42.388978Z","steps":["trace[1183799967] 'range keys from in-memory index tree' (duration: 857.92668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:42.388988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512673084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T01:41:42.389043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:41.530777Z","time spent":"858.25618ms","remote":"127.0.0.1:34890","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-25T01:41:42.389074Z","caller":"traceutil/trace.go:171","msg":"trace[846782297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:682; }","duration":"1.512817229s","start":"2026-02-25T01:41:40.87623Z","end":"2026-02-25T01:41:42.389048Z","steps":["trace[846782297] 'range keys from in-memory index tree' (duration: 1.512468657s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:42.389124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:40.876198Z","time spent":"1.512915822s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T01:41:42.38928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388428386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T01:41:42.389335Z","caller":"traceutil/trace.go:171","msg":"trace[1149943293] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:682; }","duration":"1.388501978s","start":"2026-02-25T01:41:41.000811Z","end":"2026-02-25T01:41:42.389313Z","steps":["trace[1149943293] 'range keys from in-memory index tree' (duration: 1.387990392s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:42.389361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:41.000799Z","time spent":"1.388555681s","remote":"127.0.0.1:34902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.250\" "} {"level":"warn","ts":"2026-02-25T01:41:42.38949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065666345s","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-25T01:41:42.389526Z","caller":"traceutil/trace.go:171","msg":"trace[1529973949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"1.065743988s","start":"2026-02-25T01:41:41.323772Z","end":"2026-02-25T01:41:42.389516Z","steps":["trace[1529973949] 'range keys from in-memory index tree' (duration: 1.064955472s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:42.389552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:41.323738Z","time spent":"1.06580761s","remote":"127.0.0.1:35126","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-25T01:41:42.412772Z","caller":"traceutil/trace.go:171","msg":"trace[1492345613] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"578.67353ms","start":"2026-02-25T01:41:41.834076Z","end":"2026-02-25T01:41:42.41275Z","steps":["trace[1492345613] 'process raft request' (duration: 577.637306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:42.412909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:41.834056Z","time spent":"578.777874ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:42.586706Z","caller":"traceutil/trace.go:171","msg":"trace[1850294841] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"193.624694ms","start":"2026-02-25T01:41:42.39305Z","end":"2026-02-25T01:41:42.586674Z","steps":["trace[1850294841] 'process raft request' (duration: 193.464239ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:42.58686Z","caller":"traceutil/trace.go:171","msg":"trace[231799373] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"193.735897ms","start":"2026-02-25T01:41:42.393098Z","end":"2026-02-25T01:41:42.586834Z","steps":["trace[231799373] 'process raft request' (duration: 193.493669ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:02.86801Z","caller":"traceutil/trace.go:171","msg":"trace[509321379] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"122.838622ms","start":"2026-02-25T01:42:02.745148Z","end":"2026-02-25T01:42:02.867986Z","steps":["trace[509321379] 'process raft request' (duration: 122.794551ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:02.868055Z","caller":"traceutil/trace.go:171","msg":"trace[1812043658] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"123.815754ms","start":"2026-02-25T01:42:02.744206Z","end":"2026-02-25T01:42:02.868021Z","steps":["trace[1812043658] 'process raft request' (duration: 123.593897ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:02.960312Z","caller":"traceutil/trace.go:171","msg":"trace[1982767421] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"131.656739ms","start":"2026-02-25T01:42:02.828627Z","end":"2026-02-25T01:42:02.960283Z","steps":["trace[1982767421] 'process raft request' (duration: 131.457993ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:07.016066Z","caller":"traceutil/trace.go:171","msg":"trace[1998207271] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"205.691978ms","start":"2026-02-25T01:42:06.810331Z","end":"2026-02-25T01:42:07.016023Z","steps":["trace[1998207271] 'process raft request' (duration: 182.956675ms)","trace[1998207271] 'compare' (duration: 22.537947ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:07.019364Z","caller":"traceutil/trace.go:171","msg":"trace[154862283] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:787; }","duration":"139.391243ms","start":"2026-02-25T01:42:06.879959Z","end":"2026-02-25T01:42:07.01935Z","steps":["trace[154862283] 'read index received' (duration: 113.343902ms)","trace[154862283] 'applied index is now lower than readState.Index' (duration: 26.046481ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:07.019466Z","caller":"traceutil/trace.go:171","msg":"trace[1018452720] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"208.817981ms","start":"2026-02-25T01:42:06.810622Z","end":"2026-02-25T01:42:07.01944Z","steps":["trace[1018452720] 'process raft request' (duration: 208.667086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:07.019585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.576739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:42:07.019674Z","caller":"traceutil/trace.go:171","msg":"trace[1634878982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"139.700943ms","start":"2026-02-25T01:42:06.879909Z","end":"2026-02-25T01:42:07.01961Z","steps":["trace[1634878982] 'agreement among raft nodes before linearized reading' (duration: 139.538068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:07.019751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.803845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:42:07.019833Z","caller":"traceutil/trace.go:171","msg":"trace[730004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"135.93804ms","start":"2026-02-25T01:42:06.883875Z","end":"2026-02-25T01:42:07.019813Z","steps":["trace[730004] 'agreement among raft nodes before linearized reading' (duration: 135.687732ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:09.041666Z","caller":"traceutil/trace.go:171","msg":"trace[2059486912] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"227.557282ms","start":"2026-02-25T01:42:08.814084Z","end":"2026-02-25T01:42:09.041642Z","steps":["trace[2059486912] 'process raft request' (duration: 227.316064ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:09.249532Z","caller":"traceutil/trace.go:171","msg":"trace[542498635] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"203.362012ms","start":"2026-02-25T01:42:09.046132Z","end":"2026-02-25T01:42:09.249494Z","steps":["trace[542498635] 'process raft request' (duration: 108.254176ms)","trace[542498635] 'compare' (duration: 94.951641ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:09.307957Z","caller":"traceutil/trace.go:171","msg":"trace[995272273] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"260.989784ms","start":"2026-02-25T01:42:09.046939Z","end":"2026-02-25T01:42:09.307929Z","steps":["trace[995272273] 'process raft request' (duration: 260.88436ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:09.308093Z","caller":"traceutil/trace.go:171","msg":"trace[368680120] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"261.129249ms","start":"2026-02-25T01:42:09.046948Z","end":"2026-02-25T01:42:09.308077Z","steps":["trace[368680120] 'process raft request' (duration: 260.938542ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:09.308254Z","caller":"traceutil/trace.go:171","msg":"trace[1481931087] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"261.316575ms","start":"2026-02-25T01:42:09.046928Z","end":"2026-02-25T01:42:09.308245Z","steps":["trace[1481931087] 'process raft request' (duration: 260.750277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:11.173148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.753351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-25T01:42:11.173232Z","caller":"traceutil/trace.go:171","msg":"trace[2099230624] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:768; }","duration":"130.861494ms","start":"2026-02-25T01:42:11.042352Z","end":"2026-02-25T01:42:11.173214Z","steps":["trace[2099230624] 'range keys from in-memory index tree' (duration: 130.684669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:11.173247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.426142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:42:11.173313Z","caller":"traceutil/trace.go:171","msg":"trace[1420702349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"127.523736ms","start":"2026-02-25T01:42:11.045772Z","end":"2026-02-25T01:42:11.173296Z","steps":["trace[1420702349] 'range keys from in-memory index tree' (duration: 127.297608ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:19.409641Z","caller":"traceutil/trace.go:171","msg":"trace[576063820] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"106.903192ms","start":"2026-02-25T01:42:19.302708Z","end":"2026-02-25T01:42:19.409611Z","steps":["trace[576063820] 'process raft request' (duration: 62.15244ms)","trace[576063820] 'compare' (duration: 44.525235ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:21.308142Z","caller":"traceutil/trace.go:171","msg":"trace[1270179038] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"152.7761ms","start":"2026-02-25T01:42:21.155335Z","end":"2026-02-25T01:42:21.308111Z","steps":["trace[1270179038] 'process raft request' (duration: 152.442309ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:21.428078Z","caller":"traceutil/trace.go:171","msg":"trace[160742549] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"115.954697ms","start":"2026-02-25T01:42:21.312006Z","end":"2026-02-25T01:42:21.427961Z","steps":["trace[160742549] 'process raft request' (duration: 68.135815ms)","trace[160742549] 'compare' (duration: 47.609425ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:21.42817Z","caller":"traceutil/trace.go:171","msg":"trace[512489635] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"113.973942ms","start":"2026-02-25T01:42:21.31419Z","end":"2026-02-25T01:42:21.428164Z","steps":["trace[512489635] 'process raft request' (duration: 113.851428ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:21.428125Z","caller":"traceutil/trace.go:171","msg":"trace[473985958] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"113.949161ms","start":"2026-02-25T01:42:21.314156Z","end":"2026-02-25T01:42:21.428106Z","steps":["trace[473985958] 'process raft request' (duration: 113.695773ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:21.573613Z","caller":"traceutil/trace.go:171","msg":"trace[1758063738] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:833; }","duration":"134.581116ms","start":"2026-02-25T01:42:21.439012Z","end":"2026-02-25T01:42:21.573593Z","steps":["trace[1758063738] 'read index received' (duration: 109.455415ms)","trace[1758063738] 'applied index is now lower than readState.Index' (duration: 25.124711ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:21.573791Z","caller":"traceutil/trace.go:171","msg":"trace[874428507] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"140.483049ms","start":"2026-02-25T01:42:21.433263Z","end":"2026-02-25T01:42:21.573746Z","steps":["trace[874428507] 'process raft request' (duration: 140.276142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:21.573903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.065518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:42:21.573975Z","caller":"traceutil/trace.go:171","msg":"trace[1906702257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"100.186612ms","start":"2026-02-25T01:42:21.473774Z","end":"2026-02-25T01:42:21.573961Z","steps":["trace[1906702257] 'agreement among raft nodes before linearized reading' (duration: 100.000786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:21.573924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.821103ms","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-25T01:42:21.574256Z","caller":"traceutil/trace.go:171","msg":"trace[1504600054] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"141.199462ms","start":"2026-02-25T01:42:21.432498Z","end":"2026-02-25T01:42:21.573697Z","steps":["trace[1504600054] 'process raft request' (duration: 115.961578ms)","trace[1504600054] 'compare' (duration: 24.934234ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:21.574271Z","caller":"traceutil/trace.go:171","msg":"trace[1903949470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"135.125744ms","start":"2026-02-25T01:42:21.438984Z","end":"2026-02-25T01:42:21.57411Z","steps":["trace[1903949470] 'agreement among raft nodes before linearized reading' (duration: 134.734071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.062428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.935188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:42:24.062528Z","caller":"traceutil/trace.go:171","msg":"trace[533655606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:853; }","duration":"429.075643ms","start":"2026-02-25T01:42:23.633428Z","end":"2026-02-25T01:42:24.062503Z","steps":["trace[533655606] 'range keys from in-memory index tree' (duration: 428.692771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.062548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.607965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T01:42:24.062595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:23.633412Z","time spent":"429.168336ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:42:24.062611Z","caller":"traceutil/trace.go:171","msg":"trace[1583983338] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:853; }","duration":"307.703028ms","start":"2026-02-25T01:42:23.754891Z","end":"2026-02-25T01:42:24.062594Z","steps":["trace[1583983338] 'count revisions from in-memory index tree' (duration: 307.529933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.062647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:23.754831Z","time spent":"307.806022ms","remote":"127.0.0.1:35118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:42:24.062944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.196233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:42:24.062964Z","caller":"traceutil/trace.go:171","msg":"trace[248085089] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:853; }","duration":"277.241764ms","start":"2026-02-25T01:42:23.785716Z","end":"2026-02-25T01:42:24.062958Z","steps":["trace[248085089] 'count revisions from in-memory index tree' (duration: 277.132531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.062965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.757708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:24.063006Z","caller":"traceutil/trace.go:171","msg":"trace[1502861302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:853; }","duration":"528.851242ms","start":"2026-02-25T01:42:23.534143Z","end":"2026-02-25T01:42:24.062994Z","steps":["trace[1502861302] 'range keys from in-memory index tree' (duration: 528.667076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.06304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:23.53412Z","time spent":"528.910144ms","remote":"127.0.0.1:34878","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-25T01:42:24.063327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.012333ms","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-25T01:42:24.063324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.148134ms","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-25T01:42:24.063354Z","caller":"traceutil/trace.go:171","msg":"trace[622929509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:853; }","duration":"430.093166ms","start":"2026-02-25T01:42:23.633251Z","end":"2026-02-25T01:42:24.063345Z","steps":["trace[622929509] 'range keys from in-memory index tree' (duration: 429.850968ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:24.063367Z","caller":"traceutil/trace.go:171","msg":"trace[601423555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:853; }","duration":"485.231186ms","start":"2026-02-25T01:42:23.578124Z","end":"2026-02-25T01:42:24.063355Z","steps":["trace[601423555] 'range keys from in-memory index tree' (duration: 485.01407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.063403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:23.578103Z","time spent":"485.291939ms","remote":"127.0.0.1:35126","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":"warn","ts":"2026-02-25T01:42:24.063427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.141608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:42:24.063448Z","caller":"traceutil/trace.go:171","msg":"trace[276464721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:853; }","duration":"475.182888ms","start":"2026-02-25T01:42:23.588259Z","end":"2026-02-25T01:42:24.063442Z","steps":["trace[276464721] 'range keys from in-memory index tree' (duration: 475.067395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:24.063464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:23.588249Z","time spent":"475.21066ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T01:42:24.063376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:23.633215Z","time spent":"430.153778ms","remote":"127.0.0.1:35126","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-25T01:42:27.031506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184552258299780,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:42:27.531914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184552258299780,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:42:27.918785Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.84047282s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T01:42:27.919117Z","caller":"traceutil/trace.go:171","msg":"trace[199180889] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"1.840885113s","start":"2026-02-25T01:42:26.07821Z","end":"2026-02-25T01:42:27.919095Z","steps":["trace[199180889] 'process raft request' (duration: 1.840748969s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:27.919254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.078184Z","time spent":"1.841004007s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:28.530779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000242507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T01:42:28.530892Z","caller":"traceutil/trace.go:171","msg":"trace[1229416321] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000392442s","start":"2026-02-25T01:42:26.530475Z","end":"2026-02-25T01:42:28.530868Z","steps":["trace[1229416321] 'agreement among raft nodes before linearized reading' (duration: 2.000261528s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:28.530957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.53046Z","time spent":"2.000482736s","remote":"127.0.0.1:34890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 01:42:28 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T01:42:28.553145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.843331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:28.553518Z","caller":"traceutil/trace.go:171","msg":"trace[861192689] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"2.474527848s","start":"2026-02-25T01:42:26.078959Z","end":"2026-02-25T01:42:28.553487Z","steps":["trace[861192689] 'process raft request' (duration: 2.137263673s)","trace[861192689] 'compare' (duration: 336.714717ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:28.553554Z","caller":"traceutil/trace.go:171","msg":"trace[1349075046] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"2.474239009s","start":"2026-02-25T01:42:26.079132Z","end":"2026-02-25T01:42:28.553371Z","steps":["trace[1349075046] 'process raft request' (duration: 2.474106665s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:28.55368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.078926Z","time spent":"2.474677603s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:28.553678Z","caller":"traceutil/trace.go:171","msg":"trace[1893100382] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"2.474127206s","start":"2026-02-25T01:42:26.079533Z","end":"2026-02-25T01:42:28.55366Z","steps":["trace[1893100382] 'process raft request' (duration: 2.473800205s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:28.553706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.079111Z","time spent":"2.474517038s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:28.553871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:26.079523Z","time spent":"2.474205978s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:28.709621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.969284ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T01:42:28.709732Z","caller":"traceutil/trace.go:171","msg":"trace[1539540910] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:894; }","duration":"2.179206582s","start":"2026-02-25T01:42:26.530509Z","end":"2026-02-25T01:42:28.709716Z","steps":["trace[1539540910] 'read index received' (duration: 1.388603551s)","trace[1539540910] 'applied index is now lower than readState.Index' (duration: 790.587981ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:28.709986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234511308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:42:28.710036Z","caller":"traceutil/trace.go:171","msg":"trace[1467204696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:863; }","duration":"1.234650064s","start":"2026-02-25T01:42:27.475374Z","end":"2026-02-25T01:42:28.710024Z","steps":["trace[1467204696] 'agreement among raft nodes before linearized reading' (duration: 1.234442057s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:28.71007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:27.475355Z","time spent":"1.234705875s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T01:42:28.710356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.995096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:28.710394Z","caller":"traceutil/trace.go:171","msg":"trace[1619215528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:863; }","duration":"179.084989ms","start":"2026-02-25T01:42:28.531299Z","end":"2026-02-25T01:42:28.710384Z","steps":["trace[1619215528] 'agreement among raft nodes before linearized reading' (duration: 178.986126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:28.710632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.986041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:28.71069Z","caller":"traceutil/trace.go:171","msg":"trace[66192471] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:863; }","duration":"395.095524ms","start":"2026-02-25T01:42:28.315582Z","end":"2026-02-25T01:42:28.710677Z","steps":["trace[66192471] 'agreement among raft nodes before linearized reading' (duration: 395.003871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:28.710716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:28.315564Z","time spent":"395.145405ms","remote":"127.0.0.1:35170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-02-25T01:42:28.910286Z","caller":"traceutil/trace.go:171","msg":"trace[298270379] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:898; }","duration":"192.305351ms","start":"2026-02-25T01:42:28.717961Z","end":"2026-02-25T01:42:28.910266Z","steps":["trace[298270379] 'read index received' (duration: 61.466287ms)","trace[298270379] 'applied index is now lower than readState.Index' (duration: 130.838044ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:28.910432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.450476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:28.91047Z","caller":"traceutil/trace.go:171","msg":"trace[1731451960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:865; }","duration":"192.534768ms","start":"2026-02-25T01:42:28.717924Z","end":"2026-02-25T01:42:28.910459Z","steps":["trace[1731451960] 'agreement among raft nodes before linearized reading' (duration: 192.426325ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:28.910727Z","caller":"traceutil/trace.go:171","msg":"trace[1036426335] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"196.353723ms","start":"2026-02-25T01:42:28.714351Z","end":"2026-02-25T01:42:28.910705Z","steps":["trace[1036426335] 'process raft request' (duration: 195.565288ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:40.78563Z","caller":"traceutil/trace.go:171","msg":"trace[1254975787] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"176.935088ms","start":"2026-02-25T01:42:40.608673Z","end":"2026-02-25T01:42:40.785609Z","steps":["trace[1254975787] 'process raft request' (duration: 176.769203ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:40.788263Z","caller":"traceutil/trace.go:171","msg":"trace[2087004652] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"126.825377ms","start":"2026-02-25T01:42:40.661422Z","end":"2026-02-25T01:42:40.788248Z","steps":["trace[2087004652] 'read index received' (duration: 124.183692ms)","trace[2087004652] 'applied index is now lower than readState.Index' (duration: 2.641035ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:40.788496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.031603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:42:40.788425Z","caller":"traceutil/trace.go:171","msg":"trace[1110243159] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"177.814806ms","start":"2026-02-25T01:42:40.610583Z","end":"2026-02-25T01:42:40.788398Z","steps":["trace[1110243159] 'process raft request' (duration: 177.599249ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:40.788545Z","caller":"traceutil/trace.go:171","msg":"trace[749023151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"127.152318ms","start":"2026-02-25T01:42:40.66137Z","end":"2026-02-25T01:42:40.788522Z","steps":["trace[749023151] 'agreement among raft nodes before linearized reading' (duration: 126.942661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:43.297859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184552258299899,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:42:43.53007Z","caller":"traceutil/trace.go:171","msg":"trace[263916703] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"737.532508ms","start":"2026-02-25T01:42:42.792516Z","end":"2026-02-25T01:42:43.530049Z","steps":["trace[263916703] 'process raft request' (duration: 737.387913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:43.530273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:42.792489Z","time spent":"737.704393ms","remote":"127.0.0.1:35126","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-25T01:42:43.532357Z","caller":"traceutil/trace.go:171","msg":"trace[1825483020] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"737.420194ms","start":"2026-02-25T01:42:42.794895Z","end":"2026-02-25T01:42:43.532315Z","steps":["trace[1825483020] 'process raft request' (duration: 736.67135ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:43.532612Z","caller":"traceutil/trace.go:171","msg":"trace[1335158572] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"167.805934ms","start":"2026-02-25T01:42:43.36478Z","end":"2026-02-25T01:42:43.532586Z","steps":["trace[1335158572] 'process raft request' (duration: 166.888065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:43.532859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.454801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:42:43.532931Z","caller":"traceutil/trace.go:171","msg":"trace[1940003709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:903; }","duration":"735.563005ms","start":"2026-02-25T01:42:42.797351Z","end":"2026-02-25T01:42:43.532914Z","steps":["trace[1940003709] 'agreement among raft nodes before linearized reading' (duration: 735.368278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:43.532979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:42.797343Z","time spent":"735.622646ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:42:43.532899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:42.794875Z","time spent":"737.729355ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:43.532681Z","caller":"traceutil/trace.go:171","msg":"trace[1211823502] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:938; }","duration":"735.062478ms","start":"2026-02-25T01:42:42.797381Z","end":"2026-02-25T01:42:43.532443Z","steps":["trace[1211823502] 'read index received' (duration: 732.505797ms)","trace[1211823502] 'applied index is now lower than readState.Index' (duration: 2.552611ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:43.533705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.825443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:42:43.533764Z","caller":"traceutil/trace.go:171","msg":"trace[568862098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:903; }","duration":"530.920696ms","start":"2026-02-25T01:42:43.002829Z","end":"2026-02-25T01:42:43.53375Z","steps":["trace[568862098] 'agreement among raft nodes before linearized reading' (duration: 530.764531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:43.533797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:43.002808Z","time spent":"530.980809ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T01:42:49.875451Z","caller":"traceutil/trace.go:171","msg":"trace[2048764998] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"314.005944ms","start":"2026-02-25T01:42:49.561421Z","end":"2026-02-25T01:42:49.875427Z","steps":["trace[2048764998] 'read index received' (duration: 313.821728ms)","trace[2048764998] 'applied index is now lower than readState.Index' (duration: 183.186µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:49.875551Z","caller":"traceutil/trace.go:171","msg":"trace[1260966932] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"315.253954ms","start":"2026-02-25T01:42:49.560288Z","end":"2026-02-25T01:42:49.875542Z","steps":["trace[1260966932] 'process raft request' (duration: 314.981635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:49.875746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:49.56026Z","time spent":"315.318056ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:49.875751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.309693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:42:49.875827Z","caller":"traceutil/trace.go:171","msg":"trace[1201079040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:926; }","duration":"314.433157ms","start":"2026-02-25T01:42:49.561375Z","end":"2026-02-25T01:42:49.875808Z","steps":["trace[1201079040] 'agreement among raft nodes before linearized reading' (duration: 314.236431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:49.875879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:49.561354Z","time spent":"314.50687ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:42:50.375838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184552258299957,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:42:50.432692Z","caller":"traceutil/trace.go:171","msg":"trace[933921866] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"870.733648ms","start":"2026-02-25T01:42:49.561931Z","end":"2026-02-25T01:42:50.432665Z","steps":["trace[933921866] 'process raft request' (duration: 870.539862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:50.43302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:49.561916Z","time spent":"870.852552ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:50.646484Z","caller":"traceutil/trace.go:171","msg":"trace[1972462155] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:965; }","duration":"770.94054ms","start":"2026-02-25T01:42:49.875516Z","end":"2026-02-25T01:42:50.646457Z","steps":["trace[1972462155] 'read index received' (duration: 556.90851ms)","trace[1972462155] 'applied index is now lower than readState.Index' (duration: 214.03068ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:50.646857Z","caller":"traceutil/trace.go:171","msg":"trace[1530489034] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"766.831029ms","start":"2026-02-25T01:42:49.880009Z","end":"2026-02-25T01:42:50.64684Z","steps":["trace[1530489034] 'process raft request' (duration: 675.788662ms)","trace[1530489034] 'compare' (duration: 90.459098ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:50.646992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:49.879986Z","time spent":"766.913462ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:50.647219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.409907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:42:50.64726Z","caller":"traceutil/trace.go:171","msg":"trace[1260856545] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:928; }","duration":"991.49198ms","start":"2026-02-25T01:42:49.655758Z","end":"2026-02-25T01:42:50.64725Z","steps":["trace[1260856545] 'agreement among raft nodes before linearized reading' (duration: 991.418967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:50.647293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:49.655737Z","time spent":"991.548022ms","remote":"127.0.0.1:35212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:42:50.647776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.107313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:42:50.647843Z","caller":"traceutil/trace.go:171","msg":"trace[209970009] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:928; }","duration":"219.235717ms","start":"2026-02-25T01:42:50.428593Z","end":"2026-02-25T01:42:50.647829Z","steps":["trace[209970009] 'agreement among raft nodes before linearized reading' (duration: 219.097443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:50.648212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.931547ms","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-25T01:42:50.648264Z","caller":"traceutil/trace.go:171","msg":"trace[1851073875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:928; }","duration":"406.040091ms","start":"2026-02-25T01:42:50.242212Z","end":"2026-02-25T01:42:50.648252Z","steps":["trace[1851073875] 'agreement among raft nodes before linearized reading' (duration: 405.351199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:50.648297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:50.242194Z","time spent":"406.095583ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:42:50.64821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085822603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:42:50.648488Z","caller":"traceutil/trace.go:171","msg":"trace[1118696068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:928; }","duration":"1.086130723s","start":"2026-02-25T01:42:49.56233Z","end":"2026-02-25T01:42:50.648461Z","steps":["trace[1118696068] 'agreement among raft nodes before linearized reading' (duration: 1.08510658s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:50.648638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:49.562322Z","time spent":"1.086292458s","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T01:42:52.009875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.869157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:52.009982Z","caller":"traceutil/trace.go:171","msg":"trace[399958469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"477.027993ms","start":"2026-02-25T01:42:51.532928Z","end":"2026-02-25T01:42:52.009956Z","steps":["trace[399958469] 'range keys from in-memory index tree' (duration: 476.788555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:52.010058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:51.532908Z","time spent":"477.133896ms","remote":"127.0.0.1:34890","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-25T01:42:52.010102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.901086ms","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-25T01:42:52.010202Z","caller":"traceutil/trace.go:171","msg":"trace[601174321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:931; }","duration":"130.06184ms","start":"2026-02-25T01:42:51.880117Z","end":"2026-02-25T01:42:52.010179Z","steps":["trace[601174321] 'range keys from in-memory index tree' (duration: 129.686918ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:52.719401Z","caller":"traceutil/trace.go:171","msg":"trace[528279642] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"282.045175ms","start":"2026-02-25T01:42:52.437312Z","end":"2026-02-25T01:42:52.719357Z","steps":["trace[528279642] 'read index received' (duration: 281.775127ms)","trace[528279642] 'applied index is now lower than readState.Index' (duration: 268.538µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:52.719491Z","caller":"traceutil/trace.go:171","msg":"trace[1642666209] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"704.980119ms","start":"2026-02-25T01:42:52.014478Z","end":"2026-02-25T01:42:52.719458Z","steps":["trace[1642666209] 'process raft request' (duration: 704.646148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:52.719721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.378516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:42:52.71979Z","caller":"traceutil/trace.go:171","msg":"trace[403883484] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:932; }","duration":"282.51876ms","start":"2026-02-25T01:42:52.43726Z","end":"2026-02-25T01:42:52.719779Z","steps":["trace[403883484] 'agreement among raft nodes before linearized reading' (duration: 282.302253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:52.719725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:52.014463Z","time spent":"705.154895ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:52.72024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.277687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:52.720566Z","caller":"traceutil/trace.go:171","msg":"trace[309265452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"186.442042ms","start":"2026-02-25T01:42:52.533905Z","end":"2026-02-25T01:42:52.720347Z","steps":["trace[309265452] 'agreement among raft nodes before linearized reading' (duration: 185.914165ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:53.412806Z","caller":"traceutil/trace.go:171","msg":"trace[1844468566] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"688.087777ms","start":"2026-02-25T01:42:52.724686Z","end":"2026-02-25T01:42:53.412774Z","steps":["trace[1844468566] 'process raft request' (duration: 688.022825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:53.413005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:52.724665Z","time spent":"688.239712ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:53.413071Z","caller":"traceutil/trace.go:171","msg":"trace[2146901622] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"688.358036ms","start":"2026-02-25T01:42:52.724687Z","end":"2026-02-25T01:42:53.413045Z","steps":["trace[2146901622] 'process raft request' (duration: 687.830289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:53.413209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:52.724667Z","time spent":"688.46225ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:53.644945Z","caller":"traceutil/trace.go:171","msg":"trace[381055076] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"920.181837ms","start":"2026-02-25T01:42:52.724718Z","end":"2026-02-25T01:42:53.6449Z","steps":["trace[381055076] 'process raft request' (duration: 829.139841ms)","trace[381055076] 'compare' (duration: 90.83003ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:53.645004Z","caller":"traceutil/trace.go:171","msg":"trace[536011473] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"919.178755ms","start":"2026-02-25T01:42:52.725805Z","end":"2026-02-25T01:42:53.644984Z","steps":["trace[536011473] 'process raft request' (duration: 918.995819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:53.64511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:52.725792Z","time spent":"919.256308ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:53.645141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:52.724694Z","time spent":"920.338703ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:53.645182Z","caller":"traceutil/trace.go:171","msg":"trace[481126643] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:974; }","duration":"111.508284ms","start":"2026-02-25T01:42:53.533668Z","end":"2026-02-25T01:42:53.645176Z","steps":["trace[481126643] 'read index received' (duration: 19.982982ms)","trace[481126643] 'applied index is now lower than readState.Index' (duration: 91.524632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:53.645237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.575516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:53.645259Z","caller":"traceutil/trace.go:171","msg":"trace[15922805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"111.655269ms","start":"2026-02-25T01:42:53.533597Z","end":"2026-02-25T01:42:53.645252Z","steps":["trace[15922805] 'agreement among raft nodes before linearized reading' (duration: 111.601407ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:55.046648Z","caller":"traceutil/trace.go:171","msg":"trace[1053544867] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"318.793448ms","start":"2026-02-25T01:42:54.727828Z","end":"2026-02-25T01:42:55.046621Z","steps":["trace[1053544867] 'process raft request' (duration: 318.600021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:55.046826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:54.727806Z","time spent":"318.919261ms","remote":"127.0.0.1:35126","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-25T01:42:55.943733Z","caller":"traceutil/trace.go:171","msg":"trace[959482118] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"513.632121ms","start":"2026-02-25T01:42:55.430075Z","end":"2026-02-25T01:42:55.943707Z","steps":["trace[959482118] 'process raft request' (duration: 513.470486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:55.943937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:55.430054Z","time spent":"513.756474ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:55.973514Z","caller":"traceutil/trace.go:171","msg":"trace[1640736073] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"542.018213ms","start":"2026-02-25T01:42:55.43147Z","end":"2026-02-25T01:42:55.973488Z","steps":["trace[1640736073] 'process raft request' (duration: 541.808076ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:55.973683Z","caller":"traceutil/trace.go:171","msg":"trace[1830553481] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:978; }","duration":"440.707136ms","start":"2026-02-25T01:42:55.532944Z","end":"2026-02-25T01:42:55.973651Z","steps":["trace[1830553481] 'read index received' (duration: 410.589779ms)","trace[1830553481] 'applied index is now lower than readState.Index' (duration: 30.114737ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:55.973873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:55.431453Z","time spent":"542.158787ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:55.973918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.981806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:55.974002Z","caller":"traceutil/trace.go:171","msg":"trace[1236273016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"441.103519ms","start":"2026-02-25T01:42:55.532881Z","end":"2026-02-25T01:42:55.973984Z","steps":["trace[1236273016] 'agreement among raft nodes before linearized reading' (duration: 440.897943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:55.974011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.433947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:42:55.974045Z","caller":"traceutil/trace.go:171","msg":"trace[1954753827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"323.510799ms","start":"2026-02-25T01:42:55.650525Z","end":"2026-02-25T01:42:55.974036Z","steps":["trace[1954753827] 'agreement among raft nodes before linearized reading' (duration: 323.440307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:55.97405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:55.53286Z","time spent":"441.179912ms","remote":"127.0.0.1:34890","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-25T01:42:55.97408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:55.650494Z","time spent":"323.579541ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T01:42:55.973938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.077928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:42:55.97414Z","caller":"traceutil/trace.go:171","msg":"trace[543887338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"324.308395ms","start":"2026-02-25T01:42:55.649809Z","end":"2026-02-25T01:42:55.974117Z","steps":["trace[543887338] 'agreement among raft nodes before linearized reading' (duration: 323.998926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:55.974228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:55.649795Z","time spent":"324.406108ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T01:42:56.48694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.86603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:56.487205Z","caller":"traceutil/trace.go:171","msg":"trace[980456157] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"508.475602ms","start":"2026-02-25T01:42:55.978694Z","end":"2026-02-25T01:42:56.487169Z","steps":["trace[980456157] 'process raft request' (duration: 127.300672ms)","trace[980456157] 'compare' (duration: 380.741966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:56.487434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:55.978672Z","time spent":"508.613357ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:57.499658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.605213ms","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-25T01:42:57.499742Z","caller":"traceutil/trace.go:171","msg":"trace[484938958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:944; }","duration":"440.754278ms","start":"2026-02-25T01:42:57.05897Z","end":"2026-02-25T01:42:57.499724Z","steps":["trace[484938958] 'range keys from in-memory index tree' (duration: 440.398836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:57.499781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:57.058948Z","time spent":"440.82475ms","remote":"127.0.0.1:35126","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":"warn","ts":"2026-02-25T01:42:58.698135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.962373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:58.69822Z","caller":"traceutil/trace.go:171","msg":"trace[1840674195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"165.089447ms","start":"2026-02-25T01:42:58.53311Z","end":"2026-02-25T01:42:58.698199Z","steps":["trace[1840674195] 'range keys from in-memory index tree' (duration: 164.89298ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:00.187726Z","caller":"traceutil/trace.go:171","msg":"trace[1728842804] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"203.603466ms","start":"2026-02-25T01:42:59.984096Z","end":"2026-02-25T01:43:00.1877Z","steps":["trace[1728842804] 'read index received' (duration: 203.296986ms)","trace[1728842804] 'applied index is now lower than readState.Index' (duration: 305.289µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:00.187743Z","caller":"traceutil/trace.go:171","msg":"trace[1904050599] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"228.892168ms","start":"2026-02-25T01:42:59.958823Z","end":"2026-02-25T01:43:00.187715Z","steps":["trace[1904050599] 'process raft request' (duration: 228.677691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:00.18791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.804702ms","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-25T01:43:00.188016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.095627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T01:43:00.188016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.427376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:43:00.188035Z","caller":"traceutil/trace.go:171","msg":"trace[1091014125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"188.135528ms","start":"2026-02-25T01:42:59.999894Z","end":"2026-02-25T01:43:00.188029Z","steps":["trace[1091014125] 'agreement among raft nodes before linearized reading' (duration: 188.085297ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:00.188094Z","caller":"traceutil/trace.go:171","msg":"trace[1357370405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"183.492139ms","start":"2026-02-25T01:43:00.004554Z","end":"2026-02-25T01:43:00.188046Z","steps":["trace[1357370405] 'agreement among raft nodes before linearized reading' (duration: 183.426487ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:00.188199Z","caller":"traceutil/trace.go:171","msg":"trace[672896450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:969; }","duration":"203.871814ms","start":"2026-02-25T01:42:59.984061Z","end":"2026-02-25T01:43:00.187933Z","steps":["trace[672896450] 'agreement among raft nodes before linearized reading' (duration: 203.73208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:02.511967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.732052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:43:02.512075Z","caller":"traceutil/trace.go:171","msg":"trace[1627531207] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:978; }","duration":"102.894718ms","start":"2026-02-25T01:43:02.409157Z","end":"2026-02-25T01:43:02.512051Z","steps":["trace[1627531207] 'count revisions from in-memory index tree' (duration: 102.620549ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:02.752473Z","caller":"traceutil/trace.go:171","msg":"trace[716271814] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"219.041261ms","start":"2026-02-25T01:43:02.533409Z","end":"2026-02-25T01:43:02.75245Z","steps":["trace[716271814] 'read index received' (duration: 218.774203ms)","trace[716271814] 'applied index is now lower than readState.Index' (duration: 266.198µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:43:02.7526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.181065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:43:02.752635Z","caller":"traceutil/trace.go:171","msg":"trace[1133380410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"219.273009ms","start":"2026-02-25T01:43:02.53335Z","end":"2026-02-25T01:43:02.752623Z","steps":["trace[1133380410] 'agreement among raft nodes before linearized reading' (duration: 219.197906ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:02.752584Z","caller":"traceutil/trace.go:171","msg":"trace[166636801] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"234.332563ms","start":"2026-02-25T01:43:02.518202Z","end":"2026-02-25T01:43:02.752535Z","steps":["trace[166636801] 'process raft request' (duration: 234.041784ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:03.717867Z","caller":"traceutil/trace.go:171","msg":"trace[1668813878] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"186.466224ms","start":"2026-02-25T01:43:03.531379Z","end":"2026-02-25T01:43:03.717845Z","steps":["trace[1668813878] 'read index received' (duration: 186.223086ms)","trace[1668813878] 'applied index is now lower than readState.Index' (duration: 242.288µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:03.717892Z","caller":"traceutil/trace.go:171","msg":"trace[744871154] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"214.906308ms","start":"2026-02-25T01:43:03.502966Z","end":"2026-02-25T01:43:03.717872Z","steps":["trace[744871154] 'process raft request' (duration: 214.716232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:03.718038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.743637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:43:03.718081Z","caller":"traceutil/trace.go:171","msg":"trace[201698536] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:980; }","duration":"146.825219ms","start":"2026-02-25T01:43:03.571242Z","end":"2026-02-25T01:43:03.718067Z","steps":["trace[201698536] 'agreement among raft nodes before linearized reading' (duration: 146.733006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:03.718182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.762884ms","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-25T01:43:03.718291Z","caller":"traceutil/trace.go:171","msg":"trace[671981059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:980; }","duration":"186.910468ms","start":"2026-02-25T01:43:03.531346Z","end":"2026-02-25T01:43:03.718256Z","steps":["trace[671981059] 'agreement among raft nodes before linearized reading' (duration: 186.628089ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:03.876673Z","caller":"traceutil/trace.go:171","msg":"trace[1971530277] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"153.023059ms","start":"2026-02-25T01:43:03.723628Z","end":"2026-02-25T01:43:03.876651Z","steps":["trace[1971530277] 'process raft request' (duration: 152.969817ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:03.876866Z","caller":"traceutil/trace.go:171","msg":"trace[655466153] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"153.232255ms","start":"2026-02-25T01:43:03.723612Z","end":"2026-02-25T01:43:03.876844Z","steps":["trace[655466153] 'process raft request' (duration: 130.214805ms)","trace[655466153] 'compare' (duration: 22.640988ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:04.081744Z","caller":"traceutil/trace.go:171","msg":"trace[1895723597] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"196.124294ms","start":"2026-02-25T01:43:03.885576Z","end":"2026-02-25T01:43:04.081701Z","steps":["trace[1895723597] 'process raft request' (duration: 125.712131ms)","trace[1895723597] 'compare' (duration: 70.144134ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:04.081849Z","caller":"traceutil/trace.go:171","msg":"trace[1738340473] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"192.145627ms","start":"2026-02-25T01:43:03.889662Z","end":"2026-02-25T01:43:04.081808Z","steps":["trace[1738340473] 'process raft request' (duration: 191.94357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:04.454073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.177498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:43:04.454158Z","caller":"traceutil/trace.go:171","msg":"trace[1626373247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"113.317553ms","start":"2026-02-25T01:43:04.340818Z","end":"2026-02-25T01:43:04.454135Z","steps":["trace[1626373247] 'range keys from in-memory index tree' (duration: 112.972561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:04.454238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.717454ms","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-25T01:43:04.454295Z","caller":"traceutil/trace.go:171","msg":"trace[1724988950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"252.852998ms","start":"2026-02-25T01:43:04.201428Z","end":"2026-02-25T01:43:04.454281Z","steps":["trace[1724988950] 'range keys from in-memory index tree' (duration: 252.565919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:04.454286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.088488ms","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-25T01:43:04.454372Z","caller":"traceutil/trace.go:171","msg":"trace[127801683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:987; }","duration":"110.208983ms","start":"2026-02-25T01:43:04.34414Z","end":"2026-02-25T01:43:04.454349Z","steps":["trace[127801683] 'range keys from in-memory index tree' (duration: 109.861591ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:04.572173Z","caller":"traceutil/trace.go:171","msg":"trace[671774935] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"112.792276ms","start":"2026-02-25T01:43:04.459352Z","end":"2026-02-25T01:43:04.572145Z","steps":["trace[671774935] 'process raft request' (duration: 112.59464ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:04.575968Z","caller":"traceutil/trace.go:171","msg":"trace[601376743] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"116.04578ms","start":"2026-02-25T01:43:04.459897Z","end":"2026-02-25T01:43:04.575943Z","steps":["trace[601376743] 'process raft request' (duration: 115.782822ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:04.576389Z","caller":"traceutil/trace.go:171","msg":"trace[120053341] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"116.35406ms","start":"2026-02-25T01:43:04.459961Z","end":"2026-02-25T01:43:04.576315Z","steps":["trace[120053341] 'process raft request' (duration: 115.879855ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:08.101209Z","caller":"traceutil/trace.go:171","msg":"trace[2019833353] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"207.345105ms","start":"2026-02-25T01:43:07.893827Z","end":"2026-02-25T01:43:08.101172Z","steps":["trace[2019833353] 'process raft request' (duration: 207.239402ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:35.122115Z","caller":"traceutil/trace.go:171","msg":"trace[1955003270] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"131.275849ms","start":"2026-02-25T01:43:34.990815Z","end":"2026-02-25T01:43:35.122091Z","steps":["trace[1955003270] 'read index received' (duration: 129.459171ms)","trace[1955003270] 'applied index is now lower than readState.Index' (duration: 1.815938ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:35.122234Z","caller":"traceutil/trace.go:171","msg":"trace[409121842] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"155.914582ms","start":"2026-02-25T01:43:34.966308Z","end":"2026-02-25T01:43:35.122223Z","steps":["trace[409121842] 'process raft request' (duration: 154.025771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:35.122428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.070136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-25T01:43:35.122485Z","caller":"traceutil/trace.go:171","msg":"trace[700470519] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1137; }","duration":"118.159439ms","start":"2026-02-25T01:43:35.004311Z","end":"2026-02-25T01:43:35.122471Z","steps":["trace[700470519] 'agreement among raft nodes before linearized reading' (duration: 118.012254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:35.122568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.740865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:43:35.122629Z","caller":"traceutil/trace.go:171","msg":"trace[1208962384] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1137; }","duration":"131.838118ms","start":"2026-02-25T01:43:34.99078Z","end":"2026-02-25T01:43:35.122618Z","steps":["trace[1208962384] 'agreement among raft nodes before linearized reading' (duration: 131.748746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:01.200389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.620008ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-25T01:46:10.622749Z","caller":"traceutil/trace.go:171","msg":"trace[1728260787] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"636.63486ms","start":"2026-02-25T01:46:09.986089Z","end":"2026-02-25T01:46:10.622724Z","steps":["trace[1728260787] 'process raft request' (duration: 636.460486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:10.62291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:09.986067Z","time spent":"636.749354ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:46:10.635055Z","caller":"traceutil/trace.go:171","msg":"trace[502016007] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"648.29145ms","start":"2026-02-25T01:46:09.986733Z","end":"2026-02-25T01:46:10.635025Z","steps":["trace[502016007] 'process raft request' (duration: 648.190697ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:46:10.635306Z","caller":"traceutil/trace.go:171","msg":"trace[291353236] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"648.527936ms","start":"2026-02-25T01:46:09.986719Z","end":"2026-02-25T01:46:10.635247Z","steps":["trace[291353236] 'process raft request' (duration: 648.053063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:10.635448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.815472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:46:10.635485Z","caller":"traceutil/trace.go:171","msg":"trace[1349001483] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1801; }","duration":"369.920615ms","start":"2026-02-25T01:46:10.265557Z","end":"2026-02-25T01:46:10.635477Z","steps":["trace[1349001483] 'agreement among raft nodes before linearized reading' (duration: 369.829852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:10.635515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:10.265535Z","time spent":"369.971137ms","remote":"127.0.0.1:35040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-25T01:46:10.635341Z","caller":"traceutil/trace.go:171","msg":"trace[777449576] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1877; }","duration":"369.704829ms","start":"2026-02-25T01:46:10.265609Z","end":"2026-02-25T01:46:10.635314Z","steps":["trace[777449576] 'read index received' (duration: 356.958101ms)","trace[777449576] 'applied index is now lower than readState.Index' (duration: 12.744268ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:46:10.635842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.028678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:46:10.635921Z","caller":"traceutil/trace.go:171","msg":"trace[229247495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1801; }","duration":"102.12692ms","start":"2026-02-25T01:46:10.533779Z","end":"2026-02-25T01:46:10.635906Z","steps":["trace[229247495] 'agreement among raft nodes before linearized reading' (duration: 101.995596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:46:10.635849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:09.98671Z","time spent":"648.426854ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:46:10.635592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:46:09.986693Z","time spent":"648.739452ms","remote":"127.0.0.1:35126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}