{"level":"warn","ts":"2026-02-17T16:21:24.243226Z","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-17T16:21:24.243299Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-17T16:21:24.243363Z","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-17T16:21:24.243375Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-02-17T16:21:24.243418Z","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-17T16:21:24.244044Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-02-17T16:21:24.244143Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-17T16:21:24.248242Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.858505ms"} {"level":"info","ts":"2026-02-17T16:21:24.263355Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-02-17T16:21:24.263419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T16:21:24.263438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-02-17T16:21:24.263451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T16:21:24.263457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-02-17T16:21:24.263485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-02-17T16:21:24.272507Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T16:21:24.282166Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T16:21:24.292749Z","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-17T16:21:24.306694Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T16:21:24.316005Z","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-17T16:21:24.316235Z","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-17T16:21:24.316317Z","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-17T16:21:24.316704Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T16:21:24.317007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-02-17T16:21:24.317069Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-02-17T16:21:24.328799Z","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-17T16:21:24.331339Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-02-17T16:21:24.331363Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-02-17T16:21:24.331639Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T16:21:24.332718Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T16:21:24.364341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T16:21:24.364375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T16:21:24.364396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-02-17T16:21:24.364406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-02-17T16:21:24.364413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-02-17T16:21:24.364421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-02-17T16:21:24.36445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-02-17T16:21:24.365057Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:21:24.365873Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T16:21:24.365909Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:21:24.365965Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:21:24.367925Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T16:21:24.368001Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T16:21:24.368016Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T16:21:24.368347Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:21:24.36842Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:21:24.36847Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:21:24.374028Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"warn","ts":"2026-02-17T16:21:42.457938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.951753446s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:21:42.458027Z","caller":"traceutil/trace.go:171","msg":"trace[796173364] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:415; }","duration":"1.951852526s","start":"2026-02-17T16:21:40.506161Z","end":"2026-02-17T16:21:42.458014Z","steps":["trace[796173364] 'range keys from in-memory index tree' (duration: 1.951742786s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:21:42.45853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.817172964s","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-17T16:21:42.458552Z","caller":"traceutil/trace.go:171","msg":"trace[78462607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"1.817218565s","start":"2026-02-17T16:21:40.641328Z","end":"2026-02-17T16:21:42.458547Z","steps":["trace[78462607] 'range keys from in-memory index tree' (duration: 1.816972633s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:21:42.45857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:21:40.641315Z","time spent":"1.817249635s","remote":"127.0.0.1:36898","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-17T16:21:42.458595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.838934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-17T16:21:42.458649Z","caller":"traceutil/trace.go:171","msg":"trace[1334423789] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:415; }","duration":"822.950636ms","start":"2026-02-17T16:21:41.635686Z","end":"2026-02-17T16:21:42.458636Z","steps":["trace[1334423789] 'range keys from in-memory index tree' (duration: 822.741713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:21:42.458678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:21:41.635668Z","time spent":"823.001416ms","remote":"127.0.0.1:37114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4057,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-17T16:21:42.45888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633575719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-17T16:21:42.4589Z","caller":"traceutil/trace.go:171","msg":"trace[87199980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"1.633594949s","start":"2026-02-17T16:21:40.825299Z","end":"2026-02-17T16:21:42.458894Z","steps":["trace[87199980] 'range keys from in-memory index tree' (duration: 1.633515919s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:21:42.458933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:21:40.825279Z","time spent":"1.633632459s","remote":"127.0.0.1:36898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:21:42.459029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.631615738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T16:21:42.459045Z","caller":"traceutil/trace.go:171","msg":"trace[338849349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"1.631648388s","start":"2026-02-17T16:21:40.82739Z","end":"2026-02-17T16:21:42.459038Z","steps":["trace[338849349] 'range keys from in-memory index tree' (duration: 1.631581858s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:21:42.459059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:21:40.827384Z","time spent":"1.631672268s","remote":"127.0.0.1:36898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T16:21:42.459448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310692921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-17T16:21:42.459464Z","caller":"traceutil/trace.go:171","msg":"trace[712142555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"1.310768431s","start":"2026-02-17T16:21:41.148691Z","end":"2026-02-17T16:21:42.45946Z","steps":["trace[712142555] 'range keys from in-memory index tree' (duration: 1.31058161s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:21:42.459477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:21:41.148673Z","time spent":"1.310800361s","remote":"127.0.0.1:36898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T16:21:42.459703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.446423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:21:42.459717Z","caller":"traceutil/trace.go:171","msg":"trace[1865750365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"851.488813ms","start":"2026-02-17T16:21:41.608224Z","end":"2026-02-17T16:21:42.459712Z","steps":["trace[1865750365] 'range keys from in-memory index tree' (duration: 851.398703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:21:42.459729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:21:41.608208Z","time spent":"851.517883ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:21:57.042097Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870831799872330,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T16:21:57.436552Z","caller":"traceutil/trace.go:171","msg":"trace[920001782] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"896.166226ms","start":"2026-02-17T16:21:56.540369Z","end":"2026-02-17T16:21:57.436536Z","steps":["trace[920001782] 'process raft request' (duration: 895.759445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:21:57.436951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:21:56.54036Z","time spent":"896.25957ms","remote":"127.0.0.1:36898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:21:57.437062Z","caller":"traceutil/trace.go:171","msg":"trace[814399519] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"895.229765ms","start":"2026-02-17T16:21:56.541824Z","end":"2026-02-17T16:21:57.437054Z","steps":["trace[814399519] 'read index received' (duration: 894.250374ms)","trace[814399519] 'applied index is now lower than readState.Index' (duration: 978.711µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:21:57.437174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.352551ms","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-17T16:21:57.437199Z","caller":"traceutil/trace.go:171","msg":"trace[692595439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"895.376442ms","start":"2026-02-17T16:21:56.541815Z","end":"2026-02-17T16:21:57.437191Z","steps":["trace[692595439] 'agreement among raft nodes before linearized reading' (duration: 895.33173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:21:57.437237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:21:56.541793Z","time spent":"895.433434ms","remote":"127.0.0.1:36898","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-17T16:21:57.437385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.638798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T16:21:57.43741Z","caller":"traceutil/trace.go:171","msg":"trace[231940367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"890.695729ms","start":"2026-02-17T16:21:56.546706Z","end":"2026-02-17T16:21:57.437402Z","steps":["trace[231940367] 'agreement among raft nodes before linearized reading' (duration: 890.650717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:21:57.437432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:21:56.546694Z","time spent":"890.73255ms","remote":"127.0.0.1:36898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T16:21:57.43757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.011836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:21:57.437592Z","caller":"traceutil/trace.go:171","msg":"trace[307327374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"570.061867ms","start":"2026-02-17T16:21:56.867524Z","end":"2026-02-17T16:21:57.437586Z","steps":["trace[307327374] 'agreement among raft nodes before linearized reading' (duration: 570.031185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:21:57.437609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:21:56.867508Z","time spent":"570.097578ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:21:57.437707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.94752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-17T16:21:57.437724Z","caller":"traceutil/trace.go:171","msg":"trace[1912651587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"882.007391ms","start":"2026-02-17T16:21:56.555711Z","end":"2026-02-17T16:21:57.437718Z","steps":["trace[1912651587] 'agreement among raft nodes before linearized reading' (duration: 881.975389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:21:57.43774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:21:56.555693Z","time spent":"882.043252ms","remote":"127.0.0.1:36898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:22:05.328637Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.384775898s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T16:22:05.328953Z","caller":"traceutil/trace.go:171","msg":"trace[2113020559] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.385167028s","start":"2026-02-17T16:22:03.943763Z","end":"2026-02-17T16:22:05.32893Z","steps":["trace[2113020559] 'process raft request' (duration: 1.385052922s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:05.329054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:22:03.943746Z","time spent":"1.385243161s","remote":"127.0.0.1:36828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:22:05.975304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870831799872425,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:22:06.476046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870831799872425,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:22:06.977089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870831799872425,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:22:07.477997Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870831799872425,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:22:07.865397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000422591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-17T16:22:07.86547Z","caller":"traceutil/trace.go:171","msg":"trace[359543420] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000531136s","start":"2026-02-17T16:22:05.864925Z","end":"2026-02-17T16:22:07.865456Z","steps":["trace[359543420] 'agreement among raft nodes before linearized reading' (duration: 2.000447032s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:07.865499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:22:05.864911Z","time spent":"2.000583128s","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/17 16:22:07 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-17T16:22:07.978138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870831799872425,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:22:07.997528Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.53615384s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T16:22:07.997855Z","caller":"traceutil/trace.go:171","msg":"trace[1781055675] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"2.536524786s","start":"2026-02-17T16:22:05.46131Z","end":"2026-02-17T16:22:07.997835Z","steps":["trace[1781055675] 'process raft request' (duration: 2.536420351s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:07.99795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:22:05.461301Z","time spent":"2.53658647s","remote":"127.0.0.1:36898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:22:08.000258Z","caller":"traceutil/trace.go:171","msg":"trace[1922525913] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"2.524985066s","start":"2026-02-17T16:22:05.475263Z","end":"2026-02-17T16:22:08.000248Z","steps":["trace[1922525913] 'read index received' (duration: 2.522987533s)","trace[1922525913] 'applied index is now lower than readState.Index' (duration: 1.997083ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:22:08.000377Z","caller":"traceutil/trace.go:171","msg":"trace[1681433006] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"251.316872ms","start":"2026-02-17T16:22:07.749052Z","end":"2026-02-17T16:22:08.000369Z","steps":["trace[1681433006] 'process raft request' (duration: 251.140584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:08.00051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.525242338s","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-17T16:22:08.000529Z","caller":"traceutil/trace.go:171","msg":"trace[1036212567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"2.52528311s","start":"2026-02-17T16:22:05.47524Z","end":"2026-02-17T16:22:08.000523Z","steps":["trace[1036212567] 'agreement among raft nodes before linearized reading' (duration: 2.525205626s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:08.000547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:22:05.475231Z","time spent":"2.525311801s","remote":"127.0.0.1:36898","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-17T16:22:08.000664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.521422447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T16:22:08.000684Z","caller":"traceutil/trace.go:171","msg":"trace[921006010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"2.521455978s","start":"2026-02-17T16:22:05.479223Z","end":"2026-02-17T16:22:08.000679Z","steps":["trace[921006010] 'agreement among raft nodes before linearized reading' (duration: 2.521406376s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:08.000699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:22:05.479211Z","time spent":"2.52148454s","remote":"127.0.0.1:36898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T16:22:08.0008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.134269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:22:08.000817Z","caller":"traceutil/trace.go:171","msg":"trace[1835609498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"135.185431ms","start":"2026-02-17T16:22:07.865626Z","end":"2026-02-17T16:22:08.000812Z","steps":["trace[1835609498] 'agreement among raft nodes before linearized reading' (duration: 135.15937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:08.001502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.865992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:22:08.001557Z","caller":"traceutil/trace.go:171","msg":"trace[407256621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"388.970256ms","start":"2026-02-17T16:22:07.612577Z","end":"2026-02-17T16:22:08.001548Z","steps":["trace[407256621] 'agreement among raft nodes before linearized reading' (duration: 388.902422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:08.001617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:22:07.612564Z","time spent":"389.04583ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:22:08.001871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.503463762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-17T16:22:08.001992Z","caller":"traceutil/trace.go:171","msg":"trace[52279053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"2.503608578s","start":"2026-02-17T16:22:05.498376Z","end":"2026-02-17T16:22:08.001985Z","steps":["trace[52279053] 'agreement among raft nodes before linearized reading' (duration: 2.50342711s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:08.002065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:22:05.498361Z","time spent":"2.503695103s","remote":"127.0.0.1:36898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:22:10.722352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.976458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:22:10.722405Z","caller":"traceutil/trace.go:171","msg":"trace[1334146529] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:581; }","duration":"486.064712ms","start":"2026-02-17T16:22:10.236328Z","end":"2026-02-17T16:22:10.722392Z","steps":["trace[1334146529] 'range keys from in-memory index tree' (duration: 485.897124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:10.722434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:22:10.236313Z","time spent":"486.116494ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-17T16:22:10.722533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.072492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:22:10.722552Z","caller":"traceutil/trace.go:171","msg":"trace[365845246] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:581; }","duration":"486.098133ms","start":"2026-02-17T16:22:10.23645Z","end":"2026-02-17T16:22:10.722548Z","steps":["trace[365845246] 'range keys from in-memory index tree' (duration: 486.053042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:10.722565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:22:10.236447Z","time spent":"486.114824ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-17T16:22:10.722655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.650338ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:22:10.722693Z","caller":"traceutil/trace.go:171","msg":"trace[134645378] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:581; }","duration":"216.69199ms","start":"2026-02-17T16:22:10.505997Z","end":"2026-02-17T16:22:10.722689Z","steps":["trace[134645378] 'range keys from in-memory index tree' (duration: 216.644198ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:22:10.724179Z","caller":"traceutil/trace.go:171","msg":"trace[1621790826] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:602; }","duration":"359.711082ms","start":"2026-02-17T16:22:10.364459Z","end":"2026-02-17T16:22:10.724171Z","steps":["trace[1621790826] 'read index received' (duration: 359.707242ms)","trace[1621790826] 'applied index is now lower than readState.Index' (duration: 3.18µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:22:10.724231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.776215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:22:10.724246Z","caller":"traceutil/trace.go:171","msg":"trace[248701799] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:582; }","duration":"359.825637ms","start":"2026-02-17T16:22:10.364415Z","end":"2026-02-17T16:22:10.72424Z","steps":["trace[248701799] 'agreement among raft nodes before linearized reading' (duration: 359.788586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:10.724258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:22:10.364397Z","time spent":"359.858219ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-17T16:22:10.726515Z","caller":"traceutil/trace.go:171","msg":"trace[1685896041] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"489.996527ms","start":"2026-02-17T16:22:10.236511Z","end":"2026-02-17T16:22:10.726507Z","steps":["trace[1685896041] 'process raft request' (duration: 487.565998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:10.726574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:22:10.236504Z","time spent":"490.043108ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T16:22:10.732881Z","caller":"traceutil/trace.go:171","msg":"trace[1975597674] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"166.096862ms","start":"2026-02-17T16:22:10.566776Z","end":"2026-02-17T16:22:10.732873Z","steps":["trace[1975597674] 'process raft request' (duration: 166.05246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:28.420517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.031314ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-17T16:22:28.420813Z","caller":"traceutil/trace.go:171","msg":"trace[993140602] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"199.354235ms","start":"2026-02-17T16:22:28.221442Z","end":"2026-02-17T16:22:28.420796Z","steps":["trace[993140602] 'read index received' (duration: 64.840484ms)","trace[993140602] 'applied index is now lower than readState.Index' (duration: 134.512121ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:22:28.422757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.300875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T16:22:28.422883Z","caller":"traceutil/trace.go:171","msg":"trace[591544437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:767; }","duration":"201.4515ms","start":"2026-02-17T16:22:28.221417Z","end":"2026-02-17T16:22:28.422868Z","steps":["trace[591544437] 'agreement among raft nodes before linearized reading' (duration: 201.1757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:28.424753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.207227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T16:22:28.424903Z","caller":"traceutil/trace.go:171","msg":"trace[1753388631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:768; }","duration":"202.294691ms","start":"2026-02-17T16:22:28.222527Z","end":"2026-02-17T16:22:28.424822Z","steps":["trace[1753388631] 'agreement among raft nodes before linearized reading' (duration: 202.140295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:22:28.425714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.429138ms","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-17T16:22:28.425827Z","caller":"traceutil/trace.go:171","msg":"trace[197818573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:768; }","duration":"134.568023ms","start":"2026-02-17T16:22:28.29125Z","end":"2026-02-17T16:22:28.425818Z","steps":["trace[197818573] 'agreement among raft nodes before linearized reading' (duration: 134.382946ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:23:29.078395Z","caller":"traceutil/trace.go:171","msg":"trace[1488802387] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"268.830592ms","start":"2026-02-17T16:23:28.80954Z","end":"2026-02-17T16:23:29.078371Z","steps":["trace[1488802387] 'read index received' (duration: 268.641959ms)","trace[1488802387] 'applied index is now lower than readState.Index' (duration: 187.733µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:23:29.079174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.604087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T16:23:29.080472Z","caller":"traceutil/trace.go:171","msg":"trace[1618813827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"270.928523ms","start":"2026-02-17T16:23:28.809522Z","end":"2026-02-17T16:23:29.080451Z","steps":["trace[1618813827] 'agreement among raft nodes before linearized reading' (duration: 269.450604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:23:29.079329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.170357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-17T16:23:29.080816Z","caller":"traceutil/trace.go:171","msg":"trace[1415452852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"269.680728ms","start":"2026-02-17T16:23:28.811124Z","end":"2026-02-17T16:23:29.080805Z","steps":["trace[1415452852] 'agreement among raft nodes before linearized reading' (duration: 268.015535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:23:29.079387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.560543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:23:29.080984Z","caller":"traceutil/trace.go:171","msg":"trace[1874511369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"212.177445ms","start":"2026-02-17T16:23:28.868797Z","end":"2026-02-17T16:23:29.080975Z","steps":["trace[1874511369] 'agreement among raft nodes before linearized reading' (duration: 210.569553ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:23:29.079438Z","caller":"traceutil/trace.go:171","msg":"trace[317463683] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"276.203108ms","start":"2026-02-17T16:23:28.803221Z","end":"2026-02-17T16:23:29.079424Z","steps":["trace[317463683] 'process raft request' (duration: 275.020995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:23:37.264515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.201661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T16:23:37.264594Z","caller":"traceutil/trace.go:171","msg":"trace[1788342496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"123.317514ms","start":"2026-02-17T16:23:37.141261Z","end":"2026-02-17T16:23:37.264579Z","steps":["trace[1788342496] 'range keys from in-memory index tree' (duration: 123.042309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:23:37.265138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.417805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T16:23:37.265165Z","caller":"traceutil/trace.go:171","msg":"trace[160480932] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1003; }","duration":"151.470546ms","start":"2026-02-17T16:23:37.113688Z","end":"2026-02-17T16:23:37.265159Z","steps":["trace[160480932] 'range keys from in-memory index tree' (duration: 151.344402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:23:37.265278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.059378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T16:23:37.265294Z","caller":"traceutil/trace.go:171","msg":"trace[739787605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1003; }","duration":"131.104758ms","start":"2026-02-17T16:23:37.134184Z","end":"2026-02-17T16:23:37.265289Z","steps":["trace[739787605] 'range keys from in-memory index tree' (duration: 130.895684ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:23:37.370344Z","caller":"traceutil/trace.go:171","msg":"trace[600235311] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"102.129041ms","start":"2026-02-17T16:23:37.268197Z","end":"2026-02-17T16:23:37.370326Z","steps":["trace[600235311] 'process raft request' (duration: 102.024039ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:23:37.375213Z","caller":"traceutil/trace.go:171","msg":"trace[716836436] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"105.567755ms","start":"2026-02-17T16:23:37.269621Z","end":"2026-02-17T16:23:37.375189Z","steps":["trace[716836436] 'process raft request' (duration: 105.523185ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:23:37.375605Z","caller":"traceutil/trace.go:171","msg":"trace[2071792012] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"106.017404ms","start":"2026-02-17T16:23:37.269574Z","end":"2026-02-17T16:23:37.375591Z","steps":["trace[2071792012] 'process raft request' (duration: 105.464614ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:23:38.81753Z","caller":"traceutil/trace.go:171","msg":"trace[1761866940] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"323.820503ms","start":"2026-02-17T16:23:38.49369Z","end":"2026-02-17T16:23:38.81751Z","steps":["trace[1761866940] 'read index received' (duration: 323.712011ms)","trace[1761866940] 'applied index is now lower than readState.Index' (duration: 107.412µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:23:38.817552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:23:38.21044Z","time spent":"607.106671ms","remote":"127.0.0.1:36714","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-17T16:23:38.817725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.028027ms","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-17T16:23:38.817749Z","caller":"traceutil/trace.go:171","msg":"trace[1997444518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1006; }","duration":"324.109118ms","start":"2026-02-17T16:23:38.493633Z","end":"2026-02-17T16:23:38.817742Z","steps":["trace[1997444518] 'agreement among raft nodes before linearized reading' (duration: 323.989116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:23:38.817773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:23:38.493604Z","time spent":"324.163039ms","remote":"127.0.0.1:36898","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-17T16:23:38.818362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.118549ms","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-17T16:23:38.818486Z","caller":"traceutil/trace.go:171","msg":"trace[260308790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1006; }","duration":"292.436445ms","start":"2026-02-17T16:23:38.525969Z","end":"2026-02-17T16:23:38.818406Z","steps":["trace[260308790] 'agreement among raft nodes before linearized reading' (duration: 292.021547ms)"],"step_count":1}