{"level":"warn","ts":"2026-02-24T02:23:28.466445Z","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-24T02:23:28.466571Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-24T02:23:28.466663Z","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-24T02:23:28.466677Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-02-24T02:23:28.466698Z","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-24T02:23:28.467485Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-02-24T02:23:28.467578Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-24T02:23:28.471849Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.986624ms"} {"level":"info","ts":"2026-02-24T02:23:28.47963Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-02-24T02:23:28.479814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T02:23:28.479912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-02-24T02:23:28.479962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T02:23:28.479978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-02-24T02:23:28.480153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-02-24T02:23:28.486294Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T02:23:28.488613Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T02:23:28.489625Z","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-24T02:23:28.491527Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T02:23:28.493492Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T02:23:28.493622Z","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-24T02:23:28.493672Z","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-24T02:23:28.493688Z","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-24T02:23:28.495582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-02-24T02:23:28.495738Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-02-24T02:23:28.497147Z","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-24T02:23:28.497295Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-02-24T02:23:28.49735Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-02-24T02:23:28.497481Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T02:23:28.497533Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T02:23:29.381237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T02:23:29.381309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T02:23:29.381334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-02-24T02:23:29.381353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-02-24T02:23:29.381362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-02-24T02:23:29.381381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-02-24T02:23:29.381394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-02-24T02:23:29.382337Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T02:23:29.383722Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T02:23:29.383794Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T02:23:29.383829Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T02:23:29.384264Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T02:23:29.384287Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T02:23:29.385328Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T02:23:29.385478Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T02:23:29.385522Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T02:23:29.386847Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T02:23:29.387922Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-02-24T02:24:00.234172Z","caller":"traceutil/trace.go:171","msg":"trace[1547476169] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"134.62315ms","start":"2026-02-24T02:24:00.099518Z","end":"2026-02-24T02:24:00.234141Z","steps":["trace[1547476169] 'process raft request' (duration: 134.530268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:00.500756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.290715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-02-24T02:24:00.500851Z","caller":"traceutil/trace.go:171","msg":"trace[651766548] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:513; }","duration":"131.442919ms","start":"2026-02-24T02:24:00.36939Z","end":"2026-02-24T02:24:00.500833Z","steps":["trace[651766548] 'range keys from in-memory index tree' (duration: 131.183012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:07.195667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.89286ms","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-24T02:24:07.195735Z","caller":"traceutil/trace.go:171","msg":"trace[1883623842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:533; }","duration":"196.986213ms","start":"2026-02-24T02:24:06.998735Z","end":"2026-02-24T02:24:07.195722Z","steps":["trace[1883623842] 'range keys from in-memory index tree' (duration: 196.774097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:07.195674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.462353ms","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-24T02:24:07.195817Z","caller":"traceutil/trace.go:171","msg":"trace[600268254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:533; }","duration":"200.701278ms","start":"2026-02-24T02:24:06.995109Z","end":"2026-02-24T02:24:07.19581Z","steps":["trace[600268254] 'range keys from in-memory index tree' (duration: 200.320339ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:42.966063Z","caller":"traceutil/trace.go:171","msg":"trace[1139871308] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"273.088399ms","start":"2026-02-24T02:24:42.692952Z","end":"2026-02-24T02:24:42.96604Z","steps":["trace[1139871308] 'read index received' (duration: 272.941196ms)","trace[1139871308] 'applied index is now lower than readState.Index' (duration: 146.223µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:42.96631Z","caller":"traceutil/trace.go:171","msg":"trace[973414114] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"423.215082ms","start":"2026-02-24T02:24:42.543072Z","end":"2026-02-24T02:24:42.966287Z","steps":["trace[973414114] 'process raft request' (duration: 422.831793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:42.966743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:42.543053Z","time spent":"423.308454ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:24:42.966853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.308724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T02:24:42.966922Z","caller":"traceutil/trace.go:171","msg":"trace[1028904895] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:770; }","duration":"274.01189ms","start":"2026-02-24T02:24:42.692891Z","end":"2026-02-24T02:24:42.966903Z","steps":["trace[1028904895] 'agreement among raft nodes before linearized reading' (duration: 273.266613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:51.895938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.589142ms","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-24T02:24:51.895985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.608248ms","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-24T02:24:51.896016Z","caller":"traceutil/trace.go:171","msg":"trace[1567830773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"387.717685ms","start":"2026-02-24T02:24:51.508283Z","end":"2026-02-24T02:24:51.896001Z","steps":["trace[1567830773] 'range keys from in-memory index tree' (duration: 387.399957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:51.896057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:51.508261Z","time spent":"387.790197ms","remote":"127.0.0.1:57244","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-24T02:24:51.896067Z","caller":"traceutil/trace.go:171","msg":"trace[159468496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"428.738901ms","start":"2026-02-24T02:24:51.467307Z","end":"2026-02-24T02:24:51.896046Z","steps":["trace[159468496] 'range keys from in-memory index tree' (duration: 428.393953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:51.896068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.665937ms","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-24T02:24:51.896107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:51.467284Z","time spent":"428.814005ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T02:24:51.896148Z","caller":"traceutil/trace.go:171","msg":"trace[1784143072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"415.77504ms","start":"2026-02-24T02:24:51.48035Z","end":"2026-02-24T02:24:51.896125Z","steps":["trace[1784143072] 'range keys from in-memory index tree' (duration: 415.497014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:51.89623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:51.480339Z","time spent":"415.880112ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T02:24:53.341403Z","caller":"traceutil/trace.go:171","msg":"trace[1804253076] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"210.014568ms","start":"2026-02-24T02:24:53.131364Z","end":"2026-02-24T02:24:53.341378Z","steps":["trace[1804253076] 'process raft request' (duration: 209.836044ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:53.524566Z","caller":"traceutil/trace.go:171","msg":"trace[852551262] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"158.909197ms","start":"2026-02-24T02:24:53.365447Z","end":"2026-02-24T02:24:53.524357Z","steps":["trace[852551262] 'read index received' (duration: 158.714273ms)","trace[852551262] 'applied index is now lower than readState.Index' (duration: 193.914µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:53.52463Z","caller":"traceutil/trace.go:171","msg":"trace[761226710] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"235.776ms","start":"2026-02-24T02:24:53.288821Z","end":"2026-02-24T02:24:53.524597Z","steps":["trace[761226710] 'process raft request' (duration: 235.392821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:53.524816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.336967ms","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-24T02:24:53.524906Z","caller":"traceutil/trace.go:171","msg":"trace[1352490319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"159.46663ms","start":"2026-02-24T02:24:53.365403Z","end":"2026-02-24T02:24:53.52487Z","steps":["trace[1352490319] 'agreement among raft nodes before linearized reading' (duration: 159.248125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:54.075595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.204651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:24:54.075776Z","caller":"traceutil/trace.go:171","msg":"trace[958260927] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"162.380218ms","start":"2026-02-24T02:24:53.913376Z","end":"2026-02-24T02:24:54.075757Z","steps":["trace[958260927] 'read index received' (duration: 49.901µs)","trace[958260927] 'applied index is now lower than readState.Index' (duration: 162.328877ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:54.075973Z","caller":"traceutil/trace.go:171","msg":"trace[1022070670] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"545.69385ms","start":"2026-02-24T02:24:53.530118Z","end":"2026-02-24T02:24:54.075812Z","steps":["trace[1022070670] 'process raft request' (duration: 46.868223ms)","trace[1022070670] 'compare' (duration: 498.106469ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:24:54.076052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.674085ms","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-24T02:24:54.076055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.367568ms","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-24T02:24:54.076116Z","caller":"traceutil/trace.go:171","msg":"trace[1669415625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"138.4523ms","start":"2026-02-24T02:24:53.937647Z","end":"2026-02-24T02:24:54.076099Z","steps":["trace[1669415625] 'agreement among raft nodes before linearized reading' (duration: 138.296397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:54.076101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.411059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-24T02:24:54.076163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:53.530095Z","time spent":"545.978017ms","remote":"127.0.0.1:57244","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-24T02:24:54.076099Z","caller":"traceutil/trace.go:171","msg":"trace[97978476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"162.723686ms","start":"2026-02-24T02:24:53.913361Z","end":"2026-02-24T02:24:54.076085Z","steps":["trace[97978476] 'agreement among raft nodes before linearized reading' (duration: 162.48475ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:24:54.07618Z","caller":"traceutil/trace.go:171","msg":"trace[87200668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:801; }","duration":"138.586293ms","start":"2026-02-24T02:24:53.937577Z","end":"2026-02-24T02:24:54.076163Z","steps":["trace[87200668] 'agreement among raft nodes before linearized reading' (duration: 138.337427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:54.691546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.194797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:24:54.6917Z","caller":"traceutil/trace.go:171","msg":"trace[85767537] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"611.297961ms","start":"2026-02-24T02:24:54.080386Z","end":"2026-02-24T02:24:54.691684Z","steps":["trace[85767537] 'process raft request' (duration: 611.275379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:54.691742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:54.080362Z","time spent":"611.359313ms","remote":"127.0.0.1:57244","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-24T02:24:54.691836Z","caller":"traceutil/trace.go:171","msg":"trace[1848038619] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"611.544896ms","start":"2026-02-24T02:24:54.080257Z","end":"2026-02-24T02:24:54.691802Z","steps":["trace[1848038619] 'process raft request' (duration: 409.012911ms)","trace[1848038619] 'compare' (duration: 202.075214ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:24:54.691884Z","caller":"traceutil/trace.go:171","msg":"trace[932529164] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"611.562247ms","start":"2026-02-24T02:24:54.080314Z","end":"2026-02-24T02:24:54.691876Z","steps":["trace[932529164] 'process raft request' (duration: 611.31009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:24:54.691924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:54.080305Z","time spent":"611.597898ms","remote":"127.0.0.1:57244","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-24T02:24:54.691966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:24:54.080243Z","time spent":"611.655869ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T02:24:56.225168Z","caller":"traceutil/trace.go:171","msg":"trace[202223767] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"140.685592ms","start":"2026-02-24T02:24:56.084456Z","end":"2026-02-24T02:24:56.225142Z","steps":["trace[202223767] 'process raft request' (duration: 140.541309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:08.444302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.150936ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-24T02:25:08.444477Z","caller":"traceutil/trace.go:171","msg":"trace[380982399] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"160.876243ms","start":"2026-02-24T02:25:08.28358Z","end":"2026-02-24T02:25:08.444456Z","steps":["trace[380982399] 'read index received' (duration: 39.271µs)","trace[380982399] 'applied index is now lower than readState.Index' (duration: 160.835522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:25:08.444633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.049888ms","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-24T02:25:08.444668Z","caller":"traceutil/trace.go:171","msg":"trace[1339629783] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"161.131839ms","start":"2026-02-24T02:25:08.283526Z","end":"2026-02-24T02:25:08.444658Z","steps":["trace[1339629783] 'agreement among raft nodes before linearized reading' (duration: 160.982795ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:25:14.955531Z","caller":"traceutil/trace.go:171","msg":"trace[466814752] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"117.642764ms","start":"2026-02-24T02:25:14.837873Z","end":"2026-02-24T02:25:14.955515Z","steps":["trace[466814752] 'process raft request' (duration: 117.540432ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:25:14.961871Z","caller":"traceutil/trace.go:171","msg":"trace[1528253317] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"121.846212ms","start":"2026-02-24T02:25:14.840005Z","end":"2026-02-24T02:25:14.961852Z","steps":["trace[1528253317] 'process raft request' (duration: 121.723199ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:25:22.986384Z","caller":"traceutil/trace.go:171","msg":"trace[156982477] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"389.506766ms","start":"2026-02-24T02:25:22.596857Z","end":"2026-02-24T02:25:22.986363Z","steps":["trace[156982477] 'read index received' (duration: 389.361902ms)","trace[156982477] 'applied index is now lower than readState.Index' (duration: 143.714µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:25:22.986505Z","caller":"traceutil/trace.go:171","msg":"trace[341498816] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"420.397597ms","start":"2026-02-24T02:25:22.566097Z","end":"2026-02-24T02:25:22.986495Z","steps":["trace[341498816] 'process raft request' (duration: 420.144461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:22.986703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.782704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-24T02:25:22.986692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.825024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:25:22.986744Z","caller":"traceutil/trace.go:171","msg":"trace[1858364435] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:929; }","duration":"290.876485ms","start":"2026-02-24T02:25:22.695858Z","end":"2026-02-24T02:25:22.986735Z","steps":["trace[1858364435] 'agreement among raft nodes before linearized reading' (duration: 290.723312ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:25:22.986765Z","caller":"traceutil/trace.go:171","msg":"trace[2007633882] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:929; }","duration":"389.932215ms","start":"2026-02-24T02:25:22.596814Z","end":"2026-02-24T02:25:22.986746Z","steps":["trace[2007633882] 'agreement among raft nodes before linearized reading' (duration: 389.820193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:22.986804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:22.596787Z","time spent":"390.008878ms","remote":"127.0.0.1:57280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-24T02:25:22.986988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.823889ms","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-24T02:25:22.987018Z","caller":"traceutil/trace.go:171","msg":"trace[250267890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"114.886149ms","start":"2026-02-24T02:25:22.872123Z","end":"2026-02-24T02:25:22.987009Z","steps":["trace[250267890] 'agreement among raft nodes before linearized reading' (duration: 114.799397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:22.987329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:22.566078Z","time spent":"420.454279ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:25:23.781443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.390399ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-24T02:25:23.781636Z","caller":"traceutil/trace.go:171","msg":"trace[1108899013] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:967; }","duration":"736.194943ms","start":"2026-02-24T02:25:23.045424Z","end":"2026-02-24T02:25:23.781619Z","steps":["trace[1108899013] 'read index received' (duration: 251.490056ms)","trace[1108899013] 'applied index is now lower than readState.Index' (duration: 484.703987ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:25:23.781688Z","caller":"traceutil/trace.go:171","msg":"trace[1389841972] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"791.32428ms","start":"2026-02-24T02:25:22.990332Z","end":"2026-02-24T02:25:23.781656Z","steps":["trace[1389841972] 'process raft request' (duration: 791.191007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:23.781734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.116298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:25:23.781771Z","caller":"traceutil/trace.go:171","msg":"trace[432764667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"675.16897ms","start":"2026-02-24T02:25:23.106584Z","end":"2026-02-24T02:25:23.781753Z","steps":["trace[432764667] 'agreement among raft nodes before linearized reading' (duration: 675.121669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:23.781826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:23.106573Z","time spent":"675.245101ms","remote":"127.0.0.1:57014","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-24T02:25:23.781872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:22.990311Z","time spent":"791.464453ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:25:23.78188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:22.987754Z","time spent":"794.105044ms","remote":"127.0.0.1:57048","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-24T02:25:23.781909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.4509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-24T02:25:23.781984Z","caller":"traceutil/trace.go:171","msg":"trace[17532137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:930; }","duration":"736.576352ms","start":"2026-02-24T02:25:23.04539Z","end":"2026-02-24T02:25:23.781967Z","steps":["trace[17532137] 'agreement among raft nodes before linearized reading' (duration: 736.293425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:23.782029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:23.045378Z","time spent":"736.642204ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T02:25:23.782175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.295586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T02:25:23.78227Z","caller":"traceutil/trace.go:171","msg":"trace[1272270306] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:930; }","duration":"225.362817ms","start":"2026-02-24T02:25:23.556836Z","end":"2026-02-24T02:25:23.782199Z","steps":["trace[1272270306] 'agreement among raft nodes before linearized reading' (duration: 225.262685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:23.782393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.234758ms","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-24T02:25:23.782472Z","caller":"traceutil/trace.go:171","msg":"trace[1640498968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:930; }","duration":"653.359621ms","start":"2026-02-24T02:25:23.129095Z","end":"2026-02-24T02:25:23.782454Z","steps":["trace[1640498968] 'agreement among raft nodes before linearized reading' (duration: 653.106525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:23.782508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:23.129074Z","time spent":"653.427512ms","remote":"127.0.0.1:57244","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-24T02:25:25.592611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471724505434875939,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T02:25:25.77045Z","caller":"traceutil/trace.go:171","msg":"trace[218598603] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"678.646212ms","start":"2026-02-24T02:25:25.091786Z","end":"2026-02-24T02:25:25.770432Z","steps":["trace[218598603] 'read index received' (duration: 678.486558ms)","trace[218598603] 'applied index is now lower than readState.Index' (duration: 158.894µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:25:25.770588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.775135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T02:25:25.770568Z","caller":"traceutil/trace.go:171","msg":"trace[668487816] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"775.981171ms","start":"2026-02-24T02:25:24.994559Z","end":"2026-02-24T02:25:25.77054Z","steps":["trace[668487816] 'process raft request' (duration: 775.763977ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:25:25.770621Z","caller":"traceutil/trace.go:171","msg":"trace[1909233945] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:936; }","duration":"678.878197ms","start":"2026-02-24T02:25:25.091734Z","end":"2026-02-24T02:25:25.770612Z","steps":["trace[1909233945] 'agreement among raft nodes before linearized reading' (duration: 678.787305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:25.770655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:25.091715Z","time spent":"678.933168ms","remote":"127.0.0.1:57132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-24T02:25:25.770664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.893028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T02:25:25.770703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:24.994533Z","time spent":"776.099635ms","remote":"127.0.0.1:57244","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-24T02:25:25.770748Z","caller":"traceutil/trace.go:171","msg":"trace[1828817252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"664.0244ms","start":"2026-02-24T02:25:25.106701Z","end":"2026-02-24T02:25:25.770726Z","steps":["trace[1828817252] 'agreement among raft nodes before linearized reading' (duration: 663.869996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:25.7708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:25.106681Z","time spent":"664.110402ms","remote":"127.0.0.1:57004","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-24T02:25:26.1189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.627717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:25:26.119357Z","caller":"traceutil/trace.go:171","msg":"trace[2010554770] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"241.459473ms","start":"2026-02-24T02:25:25.877717Z","end":"2026-02-24T02:25:26.119177Z","steps":["trace[2010554770] 'read index received' (duration: 38.426407ms)","trace[2010554770] 'applied index is now lower than readState.Index' (duration: 203.031566ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:25:26.119367Z","caller":"traceutil/trace.go:171","msg":"trace[1277995093] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"328.938023ms","start":"2026-02-24T02:25:25.790383Z","end":"2026-02-24T02:25:26.119321Z","steps":["trace[1277995093] 'process raft request' (duration: 125.786995ms)","trace[1277995093] 'compare' (duration: 202.527074ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:25:26.119779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:25.790334Z","time spent":"329.403664ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:25:26.119572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.846162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T02:25:26.119995Z","caller":"traceutil/trace.go:171","msg":"trace[1769571044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:937; }","duration":"242.324412ms","start":"2026-02-24T02:25:25.877658Z","end":"2026-02-24T02:25:26.119982Z","steps":["trace[1769571044] 'agreement among raft nodes before linearized reading' (duration: 241.77594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:26.12001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.176799ms","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-24T02:25:26.12008Z","caller":"traceutil/trace.go:171","msg":"trace[1243224449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:937; }","duration":"242.285881ms","start":"2026-02-24T02:25:25.877779Z","end":"2026-02-24T02:25:26.120065Z","steps":["trace[1243224449] 'agreement among raft nodes before linearized reading' (duration: 242.046656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:26.120464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.022436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-24T02:25:26.120509Z","caller":"traceutil/trace.go:171","msg":"trace[434234100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:937; }","duration":"242.083498ms","start":"2026-02-24T02:25:25.878415Z","end":"2026-02-24T02:25:26.120498Z","steps":["trace[434234100] 'agreement among raft nodes before linearized reading' (duration: 241.971936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:26.642609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.057406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:25:26.642742Z","caller":"traceutil/trace.go:171","msg":"trace[678225559] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"517.936902ms","start":"2026-02-24T02:25:26.124786Z","end":"2026-02-24T02:25:26.642722Z","steps":["trace[678225559] 'process raft request' (duration: 125.677151ms)","trace[678225559] 'compare' (duration: 391.942793ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:25:26.642805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:26.124773Z","time spent":"518.003573ms","remote":"127.0.0.1:57244","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-24T02:25:27.094241Z","caller":"traceutil/trace.go:171","msg":"trace[1511620693] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:976; }","duration":"934.251143ms","start":"2026-02-24T02:25:26.159964Z","end":"2026-02-24T02:25:27.094215Z","steps":["trace[1511620693] 'read index received' (duration: 90.512191ms)","trace[1511620693] 'applied index is now lower than readState.Index' (duration: 843.737712ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:25:27.094298Z","caller":"traceutil/trace.go:171","msg":"trace[544597173] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"969.143637ms","start":"2026-02-24T02:25:26.125121Z","end":"2026-02-24T02:25:27.094265Z","steps":["trace[544597173] 'process raft request' (duration: 946.652142ms)","trace[544597173] 'compare' (duration: 22.217469ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:25:27.094439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.443587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T02:25:27.094443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:26.125096Z","time spent":"969.2582ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T02:25:27.094497Z","caller":"traceutil/trace.go:171","msg":"trace[522629278] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:940; }","duration":"934.564ms","start":"2026-02-24T02:25:26.159916Z","end":"2026-02-24T02:25:27.09448Z","steps":["trace[522629278] 'agreement among raft nodes before linearized reading' (duration: 934.420787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:27.094542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:26.159859Z","time spent":"934.670453ms","remote":"127.0.0.1:57196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-02-24T02:25:27.094562Z","caller":"traceutil/trace.go:171","msg":"trace[925064298] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"969.378383ms","start":"2026-02-24T02:25:26.125163Z","end":"2026-02-24T02:25:27.094541Z","steps":["trace[925064298] 'process raft request' (duration: 968.988395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:27.094648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:26.125152Z","time spent":"969.452215ms","remote":"127.0.0.1:57244","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-24T02:25:27.10402Z","caller":"traceutil/trace.go:171","msg":"trace[683914766] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"772.846799ms","start":"2026-02-24T02:25:26.331155Z","end":"2026-02-24T02:25:27.104002Z","steps":["trace[683914766] 'process raft request' (duration: 772.744346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:27.104154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:25:26.331135Z","time spent":"772.947961ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:25:28.33864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.323176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:25:28.338769Z","caller":"traceutil/trace.go:171","msg":"trace[2051735640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"231.50494ms","start":"2026-02-24T02:25:28.107235Z","end":"2026-02-24T02:25:28.33874Z","steps":["trace[2051735640] 'range keys from in-memory index tree' (duration: 231.237524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:28.338773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.324772ms","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-24T02:25:28.340051Z","caller":"traceutil/trace.go:171","msg":"trace[121259753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:943; }","duration":"213.691635ms","start":"2026-02-24T02:25:28.126328Z","end":"2026-02-24T02:25:28.34002Z","steps":["trace[121259753] 'range keys from in-memory index tree' (duration: 211.961474ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:25:28.498045Z","caller":"traceutil/trace.go:171","msg":"trace[1627796729] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"152.350184ms","start":"2026-02-24T02:25:28.345667Z","end":"2026-02-24T02:25:28.498017Z","steps":["trace[1627796729] 'process raft request' (duration: 152.17599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:25:29.408079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.522558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:25:29.408315Z","caller":"traceutil/trace.go:171","msg":"trace[1165903649] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"269.163988ms","start":"2026-02-24T02:25:29.139118Z","end":"2026-02-24T02:25:29.408282Z","steps":["trace[1165903649] 'process raft request' (duration: 161.318133ms)","trace[1165903649] 'compare' (duration: 107.354554ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:25:38.011436Z","caller":"traceutil/trace.go:171","msg":"trace[259069324] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"161.555119ms","start":"2026-02-24T02:25:37.849857Z","end":"2026-02-24T02:25:38.011412Z","steps":["trace[259069324] 'process raft request' (duration: 161.405886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:26:39.301569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.957764ms","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-24T02:26:39.301683Z","caller":"traceutil/trace.go:171","msg":"trace[521524952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1277; }","duration":"106.121088ms","start":"2026-02-24T02:26:39.195543Z","end":"2026-02-24T02:26:39.301664Z","steps":["trace[521524952] 'range keys from in-memory index tree' (duration: 105.782781ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:28:45.001255Z","caller":"traceutil/trace.go:171","msg":"trace[1481068930] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"137.491837ms","start":"2026-02-24T02:28:44.863732Z","end":"2026-02-24T02:28:45.001224Z","steps":["trace[1481068930] 'process raft request' (duration: 137.287201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:51.329405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.046238ms","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-24T02:28:51.329492Z","caller":"traceutil/trace.go:171","msg":"trace[1292218625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1846; }","duration":"140.164312ms","start":"2026-02-24T02:28:51.189311Z","end":"2026-02-24T02:28:51.329475Z","steps":["trace[1292218625] 'range keys from in-memory index tree' (duration: 139.927904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:51.329392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.818155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T02:28:51.329699Z","caller":"traceutil/trace.go:171","msg":"trace[511043132] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1846; }","duration":"107.113254ms","start":"2026-02-24T02:28:51.222513Z","end":"2026-02-24T02:28:51.329626Z","steps":["trace[511043132] 'count revisions from in-memory index tree' (duration: 106.752634ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:28:51.439771Z","caller":"traceutil/trace.go:171","msg":"trace[569932471] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"105.35002ms","start":"2026-02-24T02:28:51.334392Z","end":"2026-02-24T02:28:51.439742Z","steps":["trace[569932471] 'process raft request' (duration: 105.208277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:52.132355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.598774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:28:52.132477Z","caller":"traceutil/trace.go:171","msg":"trace[842687295] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1928; }","duration":"391.450826ms","start":"2026-02-24T02:28:51.741007Z","end":"2026-02-24T02:28:52.132458Z","steps":["trace[842687295] 'read index received' (duration: 85.637947ms)","trace[842687295] 'applied index is now lower than readState.Index' (duration: 305.811539ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:28:52.132584Z","caller":"traceutil/trace.go:171","msg":"trace[465773283] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"638.964871ms","start":"2026-02-24T02:28:51.493605Z","end":"2026-02-24T02:28:52.13257Z","steps":["trace[465773283] 'process raft request' (duration: 333.0626ms)","trace[465773283] 'compare' (duration: 305.442731ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:28:52.13264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:51.493586Z","time spent":"639.022942ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:28:52.132747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.720542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T02:28:52.13286Z","caller":"traceutil/trace.go:171","msg":"trace[840792233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1848; }","duration":"391.875555ms","start":"2026-02-24T02:28:51.740963Z","end":"2026-02-24T02:28:52.132839Z","steps":["trace[840792233] 'agreement among raft nodes before linearized reading' (duration: 391.681141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:52.132898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:51.740944Z","time spent":"391.945097ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T02:28:53.061448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.963639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:28:53.061741Z","caller":"traceutil/trace.go:171","msg":"trace[1113725218] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"924.913641ms","start":"2026-02-24T02:28:52.136782Z","end":"2026-02-24T02:28:53.061696Z","steps":["trace[1113725218] 'process raft request' (duration: 249.579205ms)","trace[1113725218] 'compare' (duration: 674.575519ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:28:53.062035Z","caller":"traceutil/trace.go:171","msg":"trace[1633690834] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1929; }","duration":"917.285752ms","start":"2026-02-24T02:28:52.144703Z","end":"2026-02-24T02:28:53.061989Z","steps":["trace[1633690834] 'read index received' (duration: 241.626088ms)","trace[1633690834] 'applied index is now lower than readState.Index' (duration: 675.658004ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:28:53.062417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.669901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:28:53.062414Z","caller":"traceutil/trace.go:171","msg":"trace[1726389183] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"925.441864ms","start":"2026-02-24T02:28:52.136925Z","end":"2026-02-24T02:28:53.062367Z","steps":["trace[1726389183] 'process raft request' (duration: 924.674696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:53.06248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.34772ms","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-24T02:28:53.062509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.787758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-02-24T02:28:53.062522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.035495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-24T02:28:53.062557Z","caller":"traceutil/trace.go:171","msg":"trace[323691883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1850; }","duration":"913.455782ms","start":"2026-02-24T02:28:52.14908Z","end":"2026-02-24T02:28:53.062536Z","steps":["trace[323691883] 'agreement among raft nodes before linearized reading' (duration: 913.230487ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:28:53.062593Z","caller":"traceutil/trace.go:171","msg":"trace[2068670373] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1850; }","duration":"321.124827ms","start":"2026-02-24T02:28:52.741439Z","end":"2026-02-24T02:28:53.062564Z","steps":["trace[2068670373] 'agreement among raft nodes before linearized reading' (duration: 320.920753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:53.062603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:52.14907Z","time spent":"913.525184ms","remote":"127.0.0.1:57244","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-24T02:28:53.062595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.075748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-24T02:28:53.062629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:52.136918Z","time spent":"925.609228ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T02:28:53.062575Z","caller":"traceutil/trace.go:171","msg":"trace[1012583586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1850; }","duration":"455.89009ms","start":"2026-02-24T02:28:52.606665Z","end":"2026-02-24T02:28:53.062555Z","steps":["trace[1012583586] 'agreement among raft nodes before linearized reading' (duration: 455.701976ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:28:53.062521Z","caller":"traceutil/trace.go:171","msg":"trace[1363238245] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1850; }","duration":"917.816404ms","start":"2026-02-24T02:28:52.144682Z","end":"2026-02-24T02:28:53.062498Z","steps":["trace[1363238245] 'agreement among raft nodes before linearized reading' (duration: 917.601259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:53.062646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:52.741421Z","time spent":"321.21664ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-24T02:28:53.062717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:52.14467Z","time spent":"918.031289ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-02-24T02:28:53.0627Z","caller":"traceutil/trace.go:171","msg":"trace[1351473099] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:1850; }","duration":"355.18088ms","start":"2026-02-24T02:28:52.707462Z","end":"2026-02-24T02:28:53.062642Z","steps":["trace[1351473099] 'agreement among raft nodes before linearized reading' (duration: 354.894684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:53.062781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:52.606644Z","time spent":"456.116796ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T02:28:53.062853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:52.707441Z","time spent":"355.400445ms","remote":"127.0.0.1:57048","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.138\" "} {"level":"warn","ts":"2026-02-24T02:28:53.063282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.602472ms","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-24T02:28:53.063363Z","caller":"traceutil/trace.go:171","msg":"trace[597802367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1850; }","duration":"797.459811ms","start":"2026-02-24T02:28:52.265883Z","end":"2026-02-24T02:28:53.063343Z","steps":["trace[597802367] 'agreement among raft nodes before linearized reading' (duration: 796.487379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:53.063418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:52.265821Z","time spent":"797.581664ms","remote":"127.0.0.1:57244","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-24T02:28:53.061962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:52.136765Z","time spent":"925.088376ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:28:54.153264Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.010023415s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T02:28:54.15375Z","caller":"traceutil/trace.go:171","msg":"trace[241807155] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"1.086478534s","start":"2026-02-24T02:28:53.06725Z","end":"2026-02-24T02:28:54.153728Z","steps":["trace[241807155] 'process raft request' (duration: 1.086411773s)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:28:54.153885Z","caller":"traceutil/trace.go:171","msg":"trace[1188810927] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"1.086962146s","start":"2026-02-24T02:28:53.066873Z","end":"2026-02-24T02:28:54.153835Z","steps":["trace[1188810927] 'process raft request' (duration: 1.086675119s)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:28:54.154094Z","caller":"traceutil/trace.go:171","msg":"trace[1581743216] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"1.08584931s","start":"2026-02-24T02:28:53.068237Z","end":"2026-02-24T02:28:54.154086Z","steps":["trace[1581743216] 'process raft request' (duration: 1.085665995s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:54.154102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:53.066847Z","time spent":"1.087136379s","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T02:28:54.153939Z","caller":"traceutil/trace.go:171","msg":"trace[1255589620] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"1.085769628s","start":"2026-02-24T02:28:53.068161Z","end":"2026-02-24T02:28:54.153931Z","steps":["trace[1255589620] 'process raft request' (duration: 1.085535933s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:54.154175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:53.068221Z","time spent":"1.08589971s","remote":"127.0.0.1:57244","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-24T02:28:54.154237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:53.068152Z","time spent":"1.086051614s","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T02:28:54.15418Z","caller":"traceutil/trace.go:171","msg":"trace[95113423] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1931; }","duration":"1.070284213s","start":"2026-02-24T02:28:53.083882Z","end":"2026-02-24T02:28:54.154166Z","steps":["trace[95113423] 'read index received' (duration: 59.105601ms)","trace[95113423] 'applied index is now lower than readState.Index' (duration: 1.011176841s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:28:54.154291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070410136s","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:532"} {"level":"info","ts":"2026-02-24T02:28:54.154322Z","caller":"traceutil/trace.go:171","msg":"trace[960324356] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1854; }","duration":"1.070469327s","start":"2026-02-24T02:28:53.083845Z","end":"2026-02-24T02:28:54.154314Z","steps":["trace[960324356] 'agreement among raft nodes before linearized reading' (duration: 1.070355364s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:54.154347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:53.083835Z","time spent":"1.070507619s","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-24T02:28:54.154433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:53.067233Z","time spent":"1.086597247s","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:28:54.370496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.027479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T02:28:54.370706Z","caller":"traceutil/trace.go:171","msg":"trace[576010340] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"206.814187ms","start":"2026-02-24T02:28:54.16387Z","end":"2026-02-24T02:28:54.370685Z","steps":["trace[576010340] 'process raft request' (duration: 206.744416ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T02:28:54.370681Z","caller":"traceutil/trace.go:171","msg":"trace[1884766413] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"1.226703715s","start":"2026-02-24T02:28:53.143911Z","end":"2026-02-24T02:28:54.370615Z","steps":["trace[1884766413] 'process raft request' (duration: 1.08245517s)","trace[1884766413] 'compare' (duration: 143.903447ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T02:28:54.370789Z","caller":"traceutil/trace.go:171","msg":"trace[1266585018] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1936; }","duration":"216.481845ms","start":"2026-02-24T02:28:54.154275Z","end":"2026-02-24T02:28:54.370757Z","steps":["trace[1266585018] 'read index received' (duration: 72.123058ms)","trace[1266585018] 'applied index is now lower than readState.Index' (duration: 144.356007ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T02:28:54.37089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:53.14389Z","time spent":"1.226876598s","remote":"127.0.0.1:57048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T02:28:54.370932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26348215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:28:54.371008Z","caller":"traceutil/trace.go:171","msg":"trace[102559445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1856; }","duration":"1.263589842s","start":"2026-02-24T02:28:53.107401Z","end":"2026-02-24T02:28:54.370991Z","steps":["trace[102559445] 'agreement among raft nodes before linearized reading' (duration: 1.2634769s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:54.371049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:53.107386Z","time spent":"1.263652894s","remote":"127.0.0.1:57014","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-24T02:28:54.371045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.402811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:4 size:1383"} {"level":"info","ts":"2026-02-24T02:28:54.371131Z","caller":"traceutil/trace.go:171","msg":"trace[1304366417] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:4; response_revision:1856; }","duration":"207.530204ms","start":"2026-02-24T02:28:54.163582Z","end":"2026-02-24T02:28:54.371113Z","steps":["trace[1304366417] 'agreement among raft nodes before linearized reading' (duration: 207.35139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:54.371283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.00377ms","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-24T02:28:54.371465Z","caller":"traceutil/trace.go:171","msg":"trace[66635571] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1856; }","duration":"927.222285ms","start":"2026-02-24T02:28:53.444224Z","end":"2026-02-24T02:28:54.371447Z","steps":["trace[66635571] 'agreement among raft nodes before linearized reading' (duration: 926.546249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:54.371507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T02:28:53.444204Z","time spent":"927.290746ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T02:28:54.641304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.402498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-24T02:28:54.641406Z","caller":"traceutil/trace.go:171","msg":"trace[906940236] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1857; }","duration":"166.573771ms","start":"2026-02-24T02:28:54.474808Z","end":"2026-02-24T02:28:54.641382Z","steps":["trace[906940236] 'range keys from in-memory index tree' (duration: 166.217443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:54.641842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.135266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T02:28:54.641956Z","caller":"traceutil/trace.go:171","msg":"trace[271549272] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:1857; }","duration":"159.336301ms","start":"2026-02-24T02:28:54.482591Z","end":"2026-02-24T02:28:54.641927Z","steps":["trace[271549272] 'range keys from in-memory index tree' (duration: 159.071424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:55.371022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.308774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T02:28:55.371113Z","caller":"traceutil/trace.go:171","msg":"trace[1267986657] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1861; }","duration":"149.448497ms","start":"2026-02-24T02:28:55.221643Z","end":"2026-02-24T02:28:55.371092Z","steps":["trace[1267986657] 'count revisions from in-memory index tree' (duration: 149.181422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T02:28:55.371135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.137242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363650"} {"level":"info","ts":"2026-02-24T02:28:55.371184Z","caller":"traceutil/trace.go:171","msg":"trace[1636176193] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1861; }","duration":"144.196913ms","start":"2026-02-24T02:28:55.226972Z","end":"2026-02-24T02:28:55.371169Z","steps":["trace[1636176193] 'range keys from in-memory index tree' (duration: 143.873246ms)"],"step_count":1}