{"level":"warn","ts":"2026-02-13T00:58:58.176364Z","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-13T00:58:58.176514Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-13T00:58:58.176635Z","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-13T00:58:58.176665Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-13T00:58:58.176778Z","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-13T00:58:58.177461Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-02-13T00:58:58.17922Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-13T00:58:58.186426Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.912412ms"} {"level":"info","ts":"2026-02-13T00:58:58.196192Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-02-13T00:58:58.198146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-02-13T00:58:58.198228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-02-13T00:58:58.198274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-13T00:58:58.198677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-02-13T00:58:58.198766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-02-13T00:58:58.220091Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-13T00:58:58.230649Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-13T00:58:58.238411Z","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-13T00:58:58.24527Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-13T00:58:58.248647Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-13T00:58:58.249332Z","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-13T00:58:58.249499Z","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-13T00:58:58.25125Z","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-13T00:58:58.262739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-02-13T00:58:58.264424Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-13T00:58:58.264838Z","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-13T00:58:58.267028Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-13T00:58:58.264855Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-13T00:58:58.267808Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-13T00:58:58.268875Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-13T00:58:58.80058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-02-13T00:58:58.80062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-13T00:58:58.800647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-02-13T00:58:58.800663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-02-13T00:58:58.800672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-13T00:58:58.80068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-02-13T00:58:58.800691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-13T00:58:58.807461Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T00:58:58.812877Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-13T00:58:58.812921Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T00:58:58.814424Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T00:58:58.81698Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-13T00:58:58.81871Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-13T00:58:58.818727Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-13T00:58:58.819694Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-02-13T00:58:58.824082Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T00:58:58.824156Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T00:58:58.824178Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T00:59:27.9467Z","caller":"traceutil/trace.go:171","msg":"trace[735500745] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"537.868597ms","start":"2026-02-13T00:59:27.408815Z","end":"2026-02-13T00:59:27.946683Z","steps":["trace[735500745] 'process raft request' (duration: 537.745754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:27.947247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:59:27.408805Z","time spent":"538.142102ms","remote":"127.0.0.1:41058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T00:59:28.148359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.963622ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-13T00:59:29.048714Z","caller":"traceutil/trace.go:171","msg":"trace[728044025] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"106.294863ms","start":"2026-02-13T00:59:28.942404Z","end":"2026-02-13T00:59:29.048699Z","steps":["trace[728044025] 'read index received' (duration: 106.160511ms)","trace[728044025] 'applied index is now lower than readState.Index' (duration: 133.862µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:59:29.04891Z","caller":"traceutil/trace.go:171","msg":"trace[748649474] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"234.967123ms","start":"2026-02-13T00:59:28.813937Z","end":"2026-02-13T00:59:29.048904Z","steps":["trace[748649474] 'process raft request' (duration: 234.672098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:29.049025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.616628ms","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-13T00:59:29.049041Z","caller":"traceutil/trace.go:171","msg":"trace[303982046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"106.70086ms","start":"2026-02-13T00:59:28.942335Z","end":"2026-02-13T00:59:29.049035Z","steps":["trace[303982046] 'agreement among raft nodes before linearized reading' (duration: 106.660259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:29.049116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.066602ms","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-13T00:59:29.049129Z","caller":"traceutil/trace.go:171","msg":"trace[1317308578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"104.088813ms","start":"2026-02-13T00:59:28.945035Z","end":"2026-02-13T00:59:29.049124Z","steps":["trace[1317308578] 'agreement among raft nodes before linearized reading' (duration: 104.068813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:35.308582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.115376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T00:59:35.308692Z","caller":"traceutil/trace.go:171","msg":"trace[726261363] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"823.203986ms","start":"2026-02-13T00:59:34.48547Z","end":"2026-02-13T00:59:35.308674Z","steps":["trace[726261363] 'process raft request' (duration: 337.908386ms)","trace[726261363] 'compare' (duration: 484.806361ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:59:35.308743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:59:34.485451Z","time spent":"823.270227ms","remote":"127.0.0.1:40962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T00:59:35.32035Z","caller":"traceutil/trace.go:171","msg":"trace[523327787] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:543; }","duration":"709.018432ms","start":"2026-02-13T00:59:34.611313Z","end":"2026-02-13T00:59:35.320332Z","steps":["trace[523327787] 'read index received' (duration: 212.075325ms)","trace[523327787] 'applied index is now lower than readState.Index' (duration: 496.942447ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:59:35.320543Z","caller":"traceutil/trace.go:171","msg":"trace[88082861] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"750.367119ms","start":"2026-02-13T00:59:34.570165Z","end":"2026-02-13T00:59:35.320532Z","steps":["trace[88082861] 'process raft request' (duration: 750.094704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:35.320619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:59:34.570133Z","time spent":"750.4298ms","remote":"127.0.0.1:41150","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-13T00:59:35.320772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.45412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-13T00:59:35.320795Z","caller":"traceutil/trace.go:171","msg":"trace[939155507] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:530; }","duration":"709.496481ms","start":"2026-02-13T00:59:34.611291Z","end":"2026-02-13T00:59:35.320788Z","steps":["trace[939155507] 'agreement among raft nodes before linearized reading' (duration: 709.44453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:35.320813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:59:34.611281Z","time spent":"709.528352ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-13T00:59:35.32092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.90985ms","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-13T00:59:35.320939Z","caller":"traceutil/trace.go:171","msg":"trace[831911896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"244.966692ms","start":"2026-02-13T00:59:35.075966Z","end":"2026-02-13T00:59:35.320933Z","steps":["trace[831911896] 'agreement among raft nodes before linearized reading' (duration: 244.931241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:35.321065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.233636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-13T00:59:35.321083Z","caller":"traceutil/trace.go:171","msg":"trace[1656861144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"195.267516ms","start":"2026-02-13T00:59:35.12581Z","end":"2026-02-13T00:59:35.321078Z","steps":["trace[1656861144] 'agreement among raft nodes before linearized reading' (duration: 195.230706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:35.321168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.71754ms","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-13T00:59:35.321185Z","caller":"traceutil/trace.go:171","msg":"trace[596132126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"202.76817ms","start":"2026-02-13T00:59:35.118411Z","end":"2026-02-13T00:59:35.321179Z","steps":["trace[596132126] 'agreement among raft nodes before linearized reading' (duration: 202.739709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:35.321264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.683416ms","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-13T00:59:35.32128Z","caller":"traceutil/trace.go:171","msg":"trace[1784334827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"203.747838ms","start":"2026-02-13T00:59:35.117528Z","end":"2026-02-13T00:59:35.321275Z","steps":["trace[1784334827] 'agreement among raft nodes before linearized reading' (duration: 203.719317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:35.835511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085624022342258,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T00:59:36.14392Z","caller":"traceutil/trace.go:171","msg":"trace[128325764] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"816.505356ms","start":"2026-02-13T00:59:35.327392Z","end":"2026-02-13T00:59:36.143897Z","steps":["trace[128325764] 'process raft request' (duration: 816.396474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:36.144094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:59:35.327377Z","time spent":"816.620288ms","remote":"127.0.0.1:41150","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-13T00:59:36.257763Z","caller":"traceutil/trace.go:171","msg":"trace[1589792716] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:546; }","duration":"923.274736ms","start":"2026-02-13T00:59:35.334472Z","end":"2026-02-13T00:59:36.257747Z","steps":["trace[1589792716] 'read index received' (duration: 809.865677ms)","trace[1589792716] 'applied index is now lower than readState.Index' (duration: 113.408149ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T00:59:36.258006Z","caller":"traceutil/trace.go:171","msg":"trace[154968565] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"930.464376ms","start":"2026-02-13T00:59:35.327533Z","end":"2026-02-13T00:59:36.257997Z","steps":["trace[154968565] 'process raft request' (duration: 928.063003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:36.258083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:59:35.327527Z","time spent":"930.499236ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T00:59:36.258124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.639503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-13T00:59:36.258177Z","caller":"traceutil/trace.go:171","msg":"trace[290050761] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:534; }","duration":"923.724755ms","start":"2026-02-13T00:59:35.334439Z","end":"2026-02-13T00:59:36.258164Z","steps":["trace[290050761] 'agreement among raft nodes before linearized reading' (duration: 923.625544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:36.258211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:59:35.334433Z","time spent":"923.770456ms","remote":"127.0.0.1:40980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":6558,"request content":"key:\"/registry/configmaps/kube-system/cilium-config\" "} {"level":"warn","ts":"2026-02-13T00:59:36.258226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.982265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T00:59:36.258257Z","caller":"traceutil/trace.go:171","msg":"trace[71910337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"917.024436ms","start":"2026-02-13T00:59:35.341227Z","end":"2026-02-13T00:59:36.258251Z","steps":["trace[71910337] 'agreement among raft nodes before linearized reading' (duration: 916.977835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:36.258296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:59:35.341222Z","time spent":"917.069537ms","remote":"127.0.0.1:40904","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-13T00:59:36.258433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.937614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T00:59:36.25845Z","caller":"traceutil/trace.go:171","msg":"trace[779457080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"174.977735ms","start":"2026-02-13T00:59:36.083467Z","end":"2026-02-13T00:59:36.258444Z","steps":["trace[779457080] 'agreement among raft nodes before linearized reading' (duration: 174.949785ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:59:36.258449Z","caller":"traceutil/trace.go:171","msg":"trace[409077378] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"930.19716ms","start":"2026-02-13T00:59:35.32824Z","end":"2026-02-13T00:59:36.258437Z","steps":["trace[409077378] 'process raft request' (duration: 929.455886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:36.258518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:59:35.328232Z","time spent":"930.248341ms","remote":"127.0.0.1:41150","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-13T00:59:36.258548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.248672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cswsr\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-13T00:59:36.258566Z","caller":"traceutil/trace.go:171","msg":"trace[1359246846] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cswsr; range_end:; response_count:1; response_revision:534; }","duration":"898.288002ms","start":"2026-02-13T00:59:35.360272Z","end":"2026-02-13T00:59:36.25856Z","steps":["trace[1359246846] 'agreement among raft nodes before linearized reading' (duration: 898.251052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:36.258581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:59:35.36026Z","time spent":"898.318202ms","remote":"127.0.0.1:41068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-cswsr\" "} {"level":"info","ts":"2026-02-13T00:59:36.258635Z","caller":"traceutil/trace.go:171","msg":"trace[564119709] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"929.691341ms","start":"2026-02-13T00:59:35.328937Z","end":"2026-02-13T00:59:36.258628Z","steps":["trace[564119709] 'process raft request' (duration: 928.786315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T00:59:36.258668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T00:59:35.328929Z","time spent":"929.719862ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T00:59:38.799637Z","caller":"traceutil/trace.go:171","msg":"trace[603172170] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"274.293533ms","start":"2026-02-13T00:59:38.525313Z","end":"2026-02-13T00:59:38.799606Z","steps":["trace[603172170] 'process raft request' (duration: 267.98403ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:59:50.363231Z","caller":"traceutil/trace.go:171","msg":"trace[1728784360] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"140.037723ms","start":"2026-02-13T00:59:50.223169Z","end":"2026-02-13T00:59:50.363207Z","steps":["trace[1728784360] 'process raft request' (duration: 139.671397ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:59:50.573681Z","caller":"traceutil/trace.go:171","msg":"trace[1107292914] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"126.650134ms","start":"2026-02-13T00:59:50.447017Z","end":"2026-02-13T00:59:50.573667Z","steps":["trace[1107292914] 'process raft request' (duration: 126.109065ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:59:50.573945Z","caller":"traceutil/trace.go:171","msg":"trace[1848832519] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:726; }","duration":"109.653771ms","start":"2026-02-13T00:59:50.464284Z","end":"2026-02-13T00:59:50.573937Z","steps":["trace[1848832519] 'read index received' (duration: 108.852017ms)","trace[1848832519] 'applied index is now lower than readState.Index' (duration: 801.434µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T00:59:50.57403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.740393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-k778m\" ","response":"range_response_count:1 size:4821"} {"level":"info","ts":"2026-02-13T00:59:50.574045Z","caller":"traceutil/trace.go:171","msg":"trace[1315803089] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-k778m; range_end:; response_count:1; response_revision:709; }","duration":"109.788284ms","start":"2026-02-13T00:59:50.464252Z","end":"2026-02-13T00:59:50.57404Z","steps":["trace[1315803089] 'agreement among raft nodes before linearized reading' (duration: 109.708032ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T00:59:50.57412Z","caller":"traceutil/trace.go:171","msg":"trace[828725863] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"110.459985ms","start":"2026-02-13T00:59:50.463655Z","end":"2026-02-13T00:59:50.574115Z","steps":["trace[828725863] 'process raft request' (duration: 110.255892ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:08.824267Z","caller":"traceutil/trace.go:171","msg":"trace[173609643] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"368.549581ms","start":"2026-02-13T01:00:08.455698Z","end":"2026-02-13T01:00:08.824247Z","steps":["trace[173609643] 'read index received' (duration: 368.417898ms)","trace[173609643] 'applied index is now lower than readState.Index' (duration: 130.923µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T01:00:08.824366Z","caller":"traceutil/trace.go:171","msg":"trace[665212222] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"372.629513ms","start":"2026-02-13T01:00:08.451728Z","end":"2026-02-13T01:00:08.824358Z","steps":["trace[665212222] 'process raft request' (duration: 372.40852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:08.824464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:08.451704Z","time spent":"372.691094ms","remote":"127.0.0.1:41150","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-13T01:00:08.824546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.217861ms","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-13T01:00:08.8246Z","caller":"traceutil/trace.go:171","msg":"trace[2060081339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"356.292752ms","start":"2026-02-13T01:00:08.468294Z","end":"2026-02-13T01:00:08.824587Z","steps":["trace[2060081339] 'agreement among raft nodes before linearized reading' (duration: 356.15687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:08.824626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:08.468288Z","time spent":"356.332663ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T01:00:08.82474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.733974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-13T01:00:08.824768Z","caller":"traceutil/trace.go:171","msg":"trace[85218273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"177.788615ms","start":"2026-02-13T01:00:08.646972Z","end":"2026-02-13T01:00:08.824761Z","steps":["trace[85218273] 'agreement among raft nodes before linearized reading' (duration: 177.700904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:08.82477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.299676ms","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-13T01:00:08.824793Z","caller":"traceutil/trace.go:171","msg":"trace[1913604967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"345.334667ms","start":"2026-02-13T01:00:08.479452Z","end":"2026-02-13T01:00:08.824787Z","steps":["trace[1913604967] 'agreement among raft nodes before linearized reading' (duration: 345.274686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:08.824816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:08.479446Z","time spent":"345.365877ms","remote":"127.0.0.1:41150","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-13T01:00:08.824912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.216613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T01:00:08.824932Z","caller":"traceutil/trace.go:171","msg":"trace[1229612049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"369.249514ms","start":"2026-02-13T01:00:08.455676Z","end":"2026-02-13T01:00:08.824925Z","steps":["trace[1229612049] 'agreement among raft nodes before linearized reading' (duration: 369.184602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:08.824949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:08.455668Z","time spent":"369.276415ms","remote":"127.0.0.1:41150","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-13T01:00:11.152136Z","caller":"traceutil/trace.go:171","msg":"trace[464931824] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"317.511032ms","start":"2026-02-13T01:00:10.834596Z","end":"2026-02-13T01:00:11.152107Z","steps":["trace[464931824] 'read index received' (duration: 317.305108ms)","trace[464931824] 'applied index is now lower than readState.Index' (duration: 204.894µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T01:00:11.152249Z","caller":"traceutil/trace.go:171","msg":"trace[185186500] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"322.274468ms","start":"2026-02-13T01:00:10.829965Z","end":"2026-02-13T01:00:11.152239Z","steps":["trace[185186500] 'process raft request' (duration: 321.940992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:11.152355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:10.829953Z","time spent":"322.320198ms","remote":"127.0.0.1:41150","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-13T01:00:11.152609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.803595ms","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-13T01:00:11.152693Z","caller":"traceutil/trace.go:171","msg":"trace[966478450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"314.905906ms","start":"2026-02-13T01:00:10.837768Z","end":"2026-02-13T01:00:11.152674Z","steps":["trace[966478450] 'agreement among raft nodes before linearized reading' (duration: 314.680702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:11.152736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:10.837762Z","time spent":"314.961837ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T01:00:11.152889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.285466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T01:00:11.152929Z","caller":"traceutil/trace.go:171","msg":"trace[85442382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"318.335647ms","start":"2026-02-13T01:00:10.834581Z","end":"2026-02-13T01:00:11.152917Z","steps":["trace[85442382] 'agreement among raft nodes before linearized reading' (duration: 318.209345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:11.152956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:10.834575Z","time spent":"318.374308ms","remote":"127.0.0.1:41150","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-13T01:00:11.152969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.80127ms","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-13T01:00:11.153006Z","caller":"traceutil/trace.go:171","msg":"trace[1068020731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:768; }","duration":"311.847041ms","start":"2026-02-13T01:00:10.84115Z","end":"2026-02-13T01:00:11.152997Z","steps":["trace[1068020731] 'agreement among raft nodes before linearized reading' (duration: 311.757959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:11.153032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:10.841145Z","time spent":"311.880822ms","remote":"127.0.0.1:41150","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-13T01:00:11.153253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.366791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-13T01:00:11.153281Z","caller":"traceutil/trace.go:171","msg":"trace[265574127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:768; }","duration":"312.408471ms","start":"2026-02-13T01:00:10.840865Z","end":"2026-02-13T01:00:11.153274Z","steps":["trace[265574127] 'agreement among raft nodes before linearized reading' (duration: 312.293629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:11.15331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:10.840859Z","time spent":"312.442412ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-13T01:00:11.412411Z","caller":"traceutil/trace.go:171","msg":"trace[715247950] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"252.287342ms","start":"2026-02-13T01:00:11.160106Z","end":"2026-02-13T01:00:11.412394Z","steps":["trace[715247950] 'process raft request' (duration: 252.19799ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:11.460546Z","caller":"traceutil/trace.go:171","msg":"trace[2117353517] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"298.455343ms","start":"2026-02-13T01:00:11.162067Z","end":"2026-02-13T01:00:11.460523Z","steps":["trace[2117353517] 'process raft request' (duration: 298.386112ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:11.460643Z","caller":"traceutil/trace.go:171","msg":"trace[329218841] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"298.559295ms","start":"2026-02-13T01:00:11.162069Z","end":"2026-02-13T01:00:11.460628Z","steps":["trace[329218841] 'process raft request' (duration: 298.417772ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:11.460776Z","caller":"traceutil/trace.go:171","msg":"trace[705329004] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"299.41736ms","start":"2026-02-13T01:00:11.161353Z","end":"2026-02-13T01:00:11.460771Z","steps":["trace[705329004] 'process raft request' (duration: 298.979823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:12.764682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085624022342751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T01:00:13.264979Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085624022342751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T01:00:13.41697Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.229508538s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T01:00:13.417452Z","caller":"traceutil/trace.go:171","msg":"trace[1964293943] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"1.153303412s","start":"2026-02-13T01:00:12.264102Z","end":"2026-02-13T01:00:13.417405Z","steps":["trace[1964293943] 'read index received' (duration: 1.153048546s)","trace[1964293943] 'applied index is now lower than readState.Index' (duration: 253.886µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T01:00:13.417857Z","caller":"traceutil/trace.go:171","msg":"trace[1004470061] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"1.230471135s","start":"2026-02-13T01:00:12.187373Z","end":"2026-02-13T01:00:13.417844Z","steps":["trace[1004470061] 'process raft request' (duration: 1.229854194s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:13.418009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:12.187351Z","time spent":"1.230564468s","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T01:00:13.418386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154277648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T01:00:13.418445Z","caller":"traceutil/trace.go:171","msg":"trace[997929867] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:773; }","duration":"1.15435933s","start":"2026-02-13T01:00:12.264075Z","end":"2026-02-13T01:00:13.418434Z","steps":["trace[997929867] 'agreement among raft nodes before linearized reading' (duration: 1.154185797s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:13.418503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:12.264047Z","time spent":"1.154421831s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.200\" "} {"level":"warn","ts":"2026-02-13T01:00:13.418718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.949985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T01:00:13.41881Z","caller":"traceutil/trace.go:171","msg":"trace[1440002227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"698.064028ms","start":"2026-02-13T01:00:12.720734Z","end":"2026-02-13T01:00:13.418797Z","steps":["trace[1440002227] 'agreement among raft nodes before linearized reading' (duration: 697.959705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:13.418868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:12.72072Z","time spent":"698.139959ms","remote":"127.0.0.1:40904","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-13T01:00:13.419319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.409858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-13T01:00:13.419368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.543975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T01:00:13.419384Z","caller":"traceutil/trace.go:171","msg":"trace[359663534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"336.49798ms","start":"2026-02-13T01:00:13.082876Z","end":"2026-02-13T01:00:13.419374Z","steps":["trace[359663534] 'agreement among raft nodes before linearized reading' (duration: 336.413089ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:13.419396Z","caller":"traceutil/trace.go:171","msg":"trace[1321481685] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:773; }","duration":"102.600797ms","start":"2026-02-13T01:00:13.316784Z","end":"2026-02-13T01:00:13.419384Z","steps":["trace[1321481685] 'agreement among raft nodes before linearized reading' (duration: 102.566196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:13.419419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:13.082862Z","time spent":"336.548641ms","remote":"127.0.0.1:40906","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-13T01:00:13.419326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.546354ms","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-13T01:00:13.419599Z","caller":"traceutil/trace.go:171","msg":"trace[686150125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:773; }","duration":"262.854659ms","start":"2026-02-13T01:00:13.156738Z","end":"2026-02-13T01:00:13.419592Z","steps":["trace[686150125] 'agreement among raft nodes before linearized reading' (duration: 262.497463ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:13.545763Z","caller":"traceutil/trace.go:171","msg":"trace[362918920] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"119.520637ms","start":"2026-02-13T01:00:13.426223Z","end":"2026-02-13T01:00:13.545744Z","steps":["trace[362918920] 'process raft request' (duration: 119.428325ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:13.54589Z","caller":"traceutil/trace.go:171","msg":"trace[566080839] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"120.650578ms","start":"2026-02-13T01:00:13.425209Z","end":"2026-02-13T01:00:13.545859Z","steps":["trace[566080839] 'read index received' (duration: 117.315339ms)","trace[566080839] 'applied index is now lower than readState.Index' (duration: 3.333709ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T01:00:13.546065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.835291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T01:00:13.548222Z","caller":"traceutil/trace.go:171","msg":"trace[1495109215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:774; }","duration":"123.01723ms","start":"2026-02-13T01:00:13.42519Z","end":"2026-02-13T01:00:13.548208Z","steps":["trace[1495109215] 'agreement among raft nodes before linearized reading' (duration: 120.737789ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:23.679527Z","caller":"traceutil/trace.go:171","msg":"trace[522873586] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"232.770949ms","start":"2026-02-13T01:00:23.446732Z","end":"2026-02-13T01:00:23.679503Z","steps":["trace[522873586] 'process raft request' (duration: 232.594696ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:24.093458Z","caller":"traceutil/trace.go:171","msg":"trace[331196431] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"398.567429ms","start":"2026-02-13T01:00:23.694861Z","end":"2026-02-13T01:00:24.093429Z","steps":["trace[331196431] 'process raft request' (duration: 390.581758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:24.093565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:23.694788Z","time spent":"398.721032ms","remote":"127.0.0.1:41150","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-13T01:00:24.093779Z","caller":"traceutil/trace.go:171","msg":"trace[476776788] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"398.817614ms","start":"2026-02-13T01:00:23.694928Z","end":"2026-02-13T01:00:24.093745Z","steps":["trace[476776788] 'process raft request' (duration: 398.471978ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:24.09396Z","caller":"traceutil/trace.go:171","msg":"trace[1477679819] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"397.211495ms","start":"2026-02-13T01:00:23.696739Z","end":"2026-02-13T01:00:24.09395Z","steps":["trace[1477679819] 'process raft request' (duration: 396.92019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:24.093967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:23.69492Z","time spent":"398.933136ms","remote":"127.0.0.1:41150","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-13T01:00:24.094007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:23.696729Z","time spent":"397.254886ms","remote":"127.0.0.1:41150","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-13T01:00:24.094358Z","caller":"traceutil/trace.go:171","msg":"trace[82940190] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"395.944903ms","start":"2026-02-13T01:00:23.698404Z","end":"2026-02-13T01:00:24.094349Z","steps":["trace[82940190] 'process raft request' (duration: 395.507086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:24.094402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:23.698395Z","time spent":"395.981133ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T01:00:36.683594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.087066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T01:00:36.683681Z","caller":"traceutil/trace.go:171","msg":"trace[1375365013] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"490.404766ms","start":"2026-02-13T01:00:36.193264Z","end":"2026-02-13T01:00:36.683669Z","steps":["trace[1375365013] 'process raft request' (duration: 262.189217ms)","trace[1375365013] 'compare' (duration: 228.023845ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T01:00:36.683712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:36.193249Z","time spent":"490.448847ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T01:00:38.685311Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085624022343049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T01:00:39.185721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085624022343049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T01:00:39.462344Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.286105528s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T01:00:39.462575Z","caller":"traceutil/trace.go:171","msg":"trace[71863076] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"1.277851963s","start":"2026-02-13T01:00:38.184709Z","end":"2026-02-13T01:00:39.462561Z","steps":["trace[71863076] 'read index received' (duration: 1.27772186s)","trace[71863076] 'applied index is now lower than readState.Index' (duration: 129.703µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T01:00:39.462721Z","caller":"traceutil/trace.go:171","msg":"trace[1075132495] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"1.286563526s","start":"2026-02-13T01:00:38.17615Z","end":"2026-02-13T01:00:39.462714Z","steps":["trace[1075132495] 'process raft request' (duration: 1.286318341s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:39.462777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:38.176134Z","time spent":"1.286599516s","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T01:00:39.462916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278215649s","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-13T01:00:39.462932Z","caller":"traceutil/trace.go:171","msg":"trace[1767275796] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:894; }","duration":"1.278245609s","start":"2026-02-13T01:00:38.18468Z","end":"2026-02-13T01:00:39.462926Z","steps":["trace[1767275796] 'agreement among raft nodes before linearized reading' (duration: 1.278182978s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:39.462946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:38.184673Z","time spent":"1.27827033s","remote":"127.0.0.1:41150","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-13T01:00:39.463032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176663381s","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-13T01:00:39.463059Z","caller":"traceutil/trace.go:171","msg":"trace[1464088769] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:894; }","duration":"1.176675981s","start":"2026-02-13T01:00:38.286365Z","end":"2026-02-13T01:00:39.46304Z","steps":["trace[1464088769] 'agreement among raft nodes before linearized reading' (duration: 1.176640051s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:39.46307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:38.28633Z","time spent":"1.176737283s","remote":"127.0.0.1:41150","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-13T01:00:39.463163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.063602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T01:00:39.463177Z","caller":"traceutil/trace.go:171","msg":"trace[1825508313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"376.111064ms","start":"2026-02-13T01:00:39.087062Z","end":"2026-02-13T01:00:39.463174Z","steps":["trace[1825508313] 'agreement among raft nodes before linearized reading' (duration: 376.089043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:39.463188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:39.087047Z","time spent":"376.139084ms","remote":"127.0.0.1:40904","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-13T01:00:39.46327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.166677ms","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-13T01:00:39.463282Z","caller":"traceutil/trace.go:171","msg":"trace[1831451676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"776.199308ms","start":"2026-02-13T01:00:38.687079Z","end":"2026-02-13T01:00:39.463278Z","steps":["trace[1831451676] 'agreement among raft nodes before linearized reading' (duration: 776.165718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:39.463292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:38.687067Z","time spent":"776.222579ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-13T01:00:39.576442Z","caller":"traceutil/trace.go:171","msg":"trace[1500016736] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"106.735589ms","start":"2026-02-13T01:00:39.469692Z","end":"2026-02-13T01:00:39.576428Z","steps":["trace[1500016736] 'process raft request' (duration: 106.711779ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:39.576696Z","caller":"traceutil/trace.go:171","msg":"trace[1854120193] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"107.076786ms","start":"2026-02-13T01:00:39.469613Z","end":"2026-02-13T01:00:39.57669Z","steps":["trace[1854120193] 'process raft request' (duration: 106.71243ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:40.387311Z","caller":"traceutil/trace.go:171","msg":"trace[1739804272] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"501.730105ms","start":"2026-02-13T01:00:39.885566Z","end":"2026-02-13T01:00:40.387296Z","steps":["trace[1739804272] 'process raft request' (duration: 501.632573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:40.387411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:39.885553Z","time spent":"501.803606ms","remote":"127.0.0.1:41150","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-13T01:00:47.045067Z","caller":"traceutil/trace.go:171","msg":"trace[650947738] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"221.797436ms","start":"2026-02-13T01:00:46.823224Z","end":"2026-02-13T01:00:47.045021Z","steps":["trace[650947738] 'process raft request' (duration: 221.600052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:47.644301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.138157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T01:00:47.644355Z","caller":"traceutil/trace.go:171","msg":"trace[1384413279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"550.238889ms","start":"2026-02-13T01:00:47.094102Z","end":"2026-02-13T01:00:47.644341Z","steps":["trace[1384413279] 'range keys from in-memory index tree' (duration: 550.061136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:47.644379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:47.094078Z","time spent":"550.29666ms","remote":"127.0.0.1:40904","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-13T01:00:47.644718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.774251ms","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-13T01:00:47.645352Z","caller":"traceutil/trace.go:171","msg":"trace[1537364700] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:916; }","duration":"407.841952ms","start":"2026-02-13T01:00:47.236887Z","end":"2026-02-13T01:00:47.644729Z","steps":["trace[1537364700] 'count revisions from in-memory index tree' (duration: 407.70535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:47.645374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:47.236861Z","time spent":"408.505394ms","remote":"127.0.0.1:41346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-13T01:00:47.648787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.167647ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-13T01:00:47.648834Z","caller":"traceutil/trace.go:171","msg":"trace[1653452407] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"159.944356ms","start":"2026-02-13T01:00:47.488883Z","end":"2026-02-13T01:00:47.648827Z","steps":["trace[1653452407] 'read index received' (duration: 38.38µs)","trace[1653452407] 'applied index is now lower than readState.Index' (duration: 159.905236ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T01:00:47.64894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.057818ms","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-13T01:00:47.648955Z","caller":"traceutil/trace.go:171","msg":"trace[1683267308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:916; }","duration":"160.108429ms","start":"2026-02-13T01:00:47.488842Z","end":"2026-02-13T01:00:47.648951Z","steps":["trace[1683267308] 'agreement among raft nodes before linearized reading' (duration: 160.034678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:47.649159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.354112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T01:00:47.649174Z","caller":"traceutil/trace.go:171","msg":"trace[1639545552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:916; }","duration":"132.405392ms","start":"2026-02-13T01:00:47.516764Z","end":"2026-02-13T01:00:47.64917Z","steps":["trace[1639545552] 'agreement among raft nodes before linearized reading' (duration: 132.36304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:49.847633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.962789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T01:00:49.847699Z","caller":"traceutil/trace.go:171","msg":"trace[866941490] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"163.467949ms","start":"2026-02-13T01:00:49.68422Z","end":"2026-02-13T01:00:49.847688Z","steps":["trace[866941490] 'read index received' (duration: 9.855623ms)","trace[866941490] 'applied index is now lower than readState.Index' (duration: 153.611776ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T01:00:49.847813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.593201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-13T01:00:49.847827Z","caller":"traceutil/trace.go:171","msg":"trace[1554330548] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:924; }","duration":"163.616681ms","start":"2026-02-13T01:00:49.684204Z","end":"2026-02-13T01:00:49.847821Z","steps":["trace[1554330548] 'agreement among raft nodes before linearized reading' (duration: 163.509599ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:49.848001Z","caller":"traceutil/trace.go:171","msg":"trace[1690339445] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"169.951583ms","start":"2026-02-13T01:00:49.678044Z","end":"2026-02-13T01:00:49.847995Z","steps":["trace[1690339445] 'process raft request' (duration: 60.553827ms)","trace[1690339445] 'compare' (duration: 108.716404ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T01:00:50.893803Z","caller":"traceutil/trace.go:171","msg":"trace[674658932] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"171.122403ms","start":"2026-02-13T01:00:50.722664Z","end":"2026-02-13T01:00:50.893786Z","steps":["trace[674658932] 'read index received' (duration: 171.000641ms)","trace[674658932] 'applied index is now lower than readState.Index' (duration: 121.252µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T01:00:50.893902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.219354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T01:00:50.893922Z","caller":"traceutil/trace.go:171","msg":"trace[2075439143] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:926; }","duration":"171.300106ms","start":"2026-02-13T01:00:50.722613Z","end":"2026-02-13T01:00:50.893914Z","steps":["trace[2075439143] 'agreement among raft nodes before linearized reading' (duration: 171.233735ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:50.894127Z","caller":"traceutil/trace.go:171","msg":"trace[1755637630] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"424.106278ms","start":"2026-02-13T01:00:50.470016Z","end":"2026-02-13T01:00:50.894122Z","steps":["trace[1755637630] 'process raft request' (duration: 423.693201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:50.894173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:50.470007Z","time spent":"424.133029ms","remote":"127.0.0.1:41150","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-13T01:00:53.018042Z","caller":"traceutil/trace.go:171","msg":"trace[2015773492] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"113.644451ms","start":"2026-02-13T01:00:52.90437Z","end":"2026-02-13T01:00:53.018015Z","steps":["trace[2015773492] 'process raft request' (duration: 113.477068ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:53.932373Z","caller":"traceutil/trace.go:171","msg":"trace[1663076812] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"227.931975ms","start":"2026-02-13T01:00:53.704421Z","end":"2026-02-13T01:00:53.932352Z","steps":["trace[1663076812] 'read index received' (duration: 227.629569ms)","trace[1663076812] 'applied index is now lower than readState.Index' (duration: 301.366µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T01:00:53.932695Z","caller":"traceutil/trace.go:171","msg":"trace[563224217] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"245.510483ms","start":"2026-02-13T01:00:53.687173Z","end":"2026-02-13T01:00:53.932683Z","steps":["trace[563224217] 'process raft request' (duration: 245.012865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:53.932948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.516755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T01:00:53.932981Z","caller":"traceutil/trace.go:171","msg":"trace[447022530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"228.562146ms","start":"2026-02-13T01:00:53.704409Z","end":"2026-02-13T01:00:53.932971Z","steps":["trace[447022530] 'agreement among raft nodes before linearized reading' (duration: 228.443833ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:54.046153Z","caller":"traceutil/trace.go:171","msg":"trace[1639715849] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"102.612277ms","start":"2026-02-13T01:00:53.943521Z","end":"2026-02-13T01:00:54.046134Z","steps":["trace[1639715849] 'process raft request' (duration: 102.584566ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:54.046526Z","caller":"traceutil/trace.go:171","msg":"trace[81956277] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"103.110126ms","start":"2026-02-13T01:00:53.943408Z","end":"2026-02-13T01:00:54.046518Z","steps":["trace[81956277] 'process raft request' (duration: 40.223099ms)","trace[81956277] 'compare' (duration: 62.317517ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T01:00:54.046612Z","caller":"traceutil/trace.go:171","msg":"trace[114236933] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"103.451502ms","start":"2026-02-13T01:00:53.943155Z","end":"2026-02-13T01:00:54.046606Z","steps":["trace[114236933] 'process raft request' (duration: 102.918662ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:54.559277Z","caller":"traceutil/trace.go:171","msg":"trace[793775730] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"190.747959ms","start":"2026-02-13T01:00:54.368499Z","end":"2026-02-13T01:00:54.559247Z","steps":["trace[793775730] 'process raft request' (duration: 154.021903ms)","trace[793775730] 'compare' (duration: 36.339209ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T01:00:57.252716Z","caller":"traceutil/trace.go:171","msg":"trace[1560713531] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"219.376344ms","start":"2026-02-13T01:00:57.033322Z","end":"2026-02-13T01:00:57.252698Z","steps":["trace[1560713531] 'process raft request' (duration: 219.247401ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:00:57.253073Z","caller":"traceutil/trace.go:171","msg":"trace[88277339] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:978; }","duration":"167.011861ms","start":"2026-02-13T01:00:57.086054Z","end":"2026-02-13T01:00:57.253066Z","steps":["trace[88277339] 'read index received' (duration: 167.008311ms)","trace[88277339] 'applied index is now lower than readState.Index' (duration: 2.96µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T01:00:57.25315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.059532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T01:00:57.253168Z","caller":"traceutil/trace.go:171","msg":"trace[1574514345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"167.129313ms","start":"2026-02-13T01:00:57.086033Z","end":"2026-02-13T01:00:57.253163Z","steps":["trace[1574514345] 'agreement among raft nodes before linearized reading' (duration: 167.062711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:57.754822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085624022343189,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T01:00:57.852721Z","caller":"traceutil/trace.go:171","msg":"trace[1967616298] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"756.953059ms","start":"2026-02-13T01:00:57.095716Z","end":"2026-02-13T01:00:57.852669Z","steps":["trace[1967616298] 'process raft request' (duration: 756.030493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:57.852913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:57.095706Z","time spent":"757.111143ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T01:00:57.855146Z","caller":"traceutil/trace.go:171","msg":"trace[1224567197] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"600.522185ms","start":"2026-02-13T01:00:57.254603Z","end":"2026-02-13T01:00:57.855125Z","steps":["trace[1224567197] 'read index received' (duration: 598.352926ms)","trace[1224567197] 'applied index is now lower than readState.Index' (duration: 2.167959ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T01:00:57.855333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.727558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T01:00:57.856006Z","caller":"traceutil/trace.go:171","msg":"trace[1365049639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"601.447491ms","start":"2026-02-13T01:00:57.254539Z","end":"2026-02-13T01:00:57.855986Z","steps":["trace[1365049639] 'agreement among raft nodes before linearized reading' (duration: 600.746819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:57.8562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:57.254514Z","time spent":"601.675986ms","remote":"127.0.0.1:40906","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-13T01:00:57.85545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.948376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T01:00:57.856303Z","caller":"traceutil/trace.go:171","msg":"trace[1935328979] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:944; }","duration":"390.869824ms","start":"2026-02-13T01:00:57.465427Z","end":"2026-02-13T01:00:57.856297Z","steps":["trace[1935328979] 'agreement among raft nodes before linearized reading' (duration: 389.960717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:00:57.85632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:00:57.465404Z","time spent":"390.908925ms","remote":"127.0.0.1:41388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-13T01:01:15.764694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.127498ms","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-13T01:01:15.764777Z","caller":"traceutil/trace.go:171","msg":"trace[325109311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1060; }","duration":"420.27715ms","start":"2026-02-13T01:01:15.344479Z","end":"2026-02-13T01:01:15.764756Z","steps":["trace[325109311] 'range keys from in-memory index tree' (duration: 419.901704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:01:15.76481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:01:15.344442Z","time spent":"420.360582ms","remote":"127.0.0.1:41150","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-13T01:04:24.031842Z","caller":"traceutil/trace.go:171","msg":"trace[2107211408] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"340.325286ms","start":"2026-02-13T01:04:23.691484Z","end":"2026-02-13T01:04:24.031809Z","steps":["trace[2107211408] 'process raft request' (duration: 339.688485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T01:04:24.0347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T01:04:23.691466Z","time spent":"340.478109ms","remote":"127.0.0.1:41150","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-13T01:04:32.40494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.058845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-13T01:04:32.405036Z","caller":"traceutil/trace.go:171","msg":"trace[1183581840] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:1896; }","duration":"125.193948ms","start":"2026-02-13T01:04:32.279822Z","end":"2026-02-13T01:04:32.405015Z","steps":["trace[1183581840] 'range keys from in-memory index tree' (duration: 124.879302ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:04:32.788166Z","caller":"traceutil/trace.go:171","msg":"trace[1269635536] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"228.982195ms","start":"2026-02-13T01:04:32.559162Z","end":"2026-02-13T01:04:32.788144Z","steps":["trace[1269635536] 'process raft request' (duration: 173.524128ms)","trace[1269635536] 'compare' (duration: 55.083711ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T01:04:33.006826Z","caller":"traceutil/trace.go:171","msg":"trace[1593698381] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1977; }","duration":"174.904603ms","start":"2026-02-13T01:04:32.8319Z","end":"2026-02-13T01:04:33.006804Z","steps":["trace[1593698381] 'read index received' (duration: 81.49188ms)","trace[1593698381] 'applied index is now lower than readState.Index' (duration: 93.411753ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T01:04:33.00725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.34158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T01:04:33.007485Z","caller":"traceutil/trace.go:171","msg":"trace[1479767757] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1898; }","duration":"175.663107ms","start":"2026-02-13T01:04:32.831812Z","end":"2026-02-13T01:04:33.007475Z","steps":["trace[1479767757] 'agreement among raft nodes before linearized reading' (duration: 175.394282ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T01:04:33.007375Z","caller":"traceutil/trace.go:171","msg":"trace[674234290] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"207.093285ms","start":"2026-02-13T01:04:32.800196Z","end":"2026-02-13T01:04:33.007289Z","steps":["trace[674234290] 'process raft request' (duration: 113.242225ms)","trace[674234290] 'compare' (duration: 92.919434ms)"],"step_count":2}