{"level":"warn","ts":"2026-02-19T05:55:32.662781Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-19T05:55:32.662873Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74: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.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-19T05:55:32.662941Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-19T05:55:32.66295Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-19T05:55:32.662967Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-19T05:55:32.663636Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]} {"level":"info","ts":"2026-02-19T05:55:32.663711Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-19T05:55:32.667828Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.902156ms"} {"level":"info","ts":"2026-02-19T05:55:32.674746Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-02-19T05:55:32.676736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T05:55:32.676769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-02-19T05:55:32.676784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T05:55:32.676797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-02-19T05:55:32.676907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-02-19T05:55:32.699322Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T05:55:32.70143Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T05:55:32.702588Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-19T05:55:32.704854Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T05:55:32.705161Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T05:55:32.705128Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-19T05:55:32.70524Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-19T05:55:32.705385Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-19T05:55:32.70565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-02-19T05:55:32.705753Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-19T05:55:32.707669Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-19T05:55:32.707944Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T05:55:32.707972Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T05:55:32.708115Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-19T05:55:32.708137Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-19T05:55:32.97909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T05:55:32.979149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T05:55:32.979181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-02-19T05:55:32.979199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-02-19T05:55:32.97921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-19T05:55:32.979226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-02-19T05:55:32.97924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-19T05:55:32.980053Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T05:55:32.980125Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T05:55:32.980111Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T05:55:32.980357Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T05:55:32.982129Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T05:55:32.983542Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-02-19T05:55:32.985127Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T05:55:32.985186Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T05:55:32.985484Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T05:55:32.985502Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T05:55:32.987084Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T05:55:58.066805Z","caller":"traceutil/trace.go:171","msg":"trace[921050196] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"168.851394ms","start":"2026-02-19T05:55:57.897935Z","end":"2026-02-19T05:55:58.066786Z","steps":["trace[921050196] 'process raft request' (duration: 168.744992ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:55:58.067437Z","caller":"traceutil/trace.go:171","msg":"trace[324522727] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"141.953107ms","start":"2026-02-19T05:55:57.925471Z","end":"2026-02-19T05:55:58.067424Z","steps":["trace[324522727] 'process raft request' (duration: 141.893136ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:56:02.265728Z","caller":"traceutil/trace.go:171","msg":"trace[192432086] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"182.416325ms","start":"2026-02-19T05:56:02.083275Z","end":"2026-02-19T05:56:02.265691Z","steps":["trace[192432086] 'process raft request' (duration: 182.25108ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:56:05.353244Z","caller":"traceutil/trace.go:171","msg":"trace[155162699] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"301.184587ms","start":"2026-02-19T05:56:05.052035Z","end":"2026-02-19T05:56:05.35322Z","steps":["trace[155162699] 'process raft request' (duration: 301.057464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:05.353828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:56:05.052013Z","time spent":"301.32567ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:56:08.266819Z","caller":"traceutil/trace.go:171","msg":"trace[1112801507] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"134.538332ms","start":"2026-02-19T05:56:08.132256Z","end":"2026-02-19T05:56:08.266794Z","steps":["trace[1112801507] 'process raft request' (duration: 134.378919ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:56:09.938776Z","caller":"traceutil/trace.go:171","msg":"trace[1080952685] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"208.299288ms","start":"2026-02-19T05:56:09.73045Z","end":"2026-02-19T05:56:09.938749Z","steps":["trace[1080952685] 'process raft request' (duration: 208.077373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:10.947995Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360377581783700,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T05:56:11.39395Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.094851231s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T05:56:11.394264Z","caller":"traceutil/trace.go:171","msg":"trace[91369102] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"947.207058ms","start":"2026-02-19T05:56:10.447033Z","end":"2026-02-19T05:56:11.39424Z","steps":["trace[91369102] 'read index received' (duration: 947.174867ms)","trace[91369102] 'applied index is now lower than readState.Index' (duration: 31.321µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:56:11.394298Z","caller":"traceutil/trace.go:171","msg":"trace[110378936] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.09529235s","start":"2026-02-19T05:56:10.298987Z","end":"2026-02-19T05:56:11.39428Z","steps":["trace[110378936] 'process raft request' (duration: 1.095144897s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:11.394487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:56:10.298975Z","time spent":"1.095360592s","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:56:11.394629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.558635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-19T05:56:11.395578Z","caller":"traceutil/trace.go:171","msg":"trace[1965835503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"948.510687ms","start":"2026-02-19T05:56:10.447017Z","end":"2026-02-19T05:56:11.395528Z","steps":["trace[1965835503] 'agreement among raft nodes before linearized reading' (duration: 947.32453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:11.395757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:56:10.446954Z","time spent":"948.744092ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T05:56:11.99189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.042801ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T05:56:11.992113Z","caller":"traceutil/trace.go:171","msg":"trace[1041046752] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"597.775632ms","start":"2026-02-19T05:56:11.394311Z","end":"2026-02-19T05:56:11.992087Z","steps":["trace[1041046752] 'read index received' (duration: 1.738269ms)","trace[1041046752] 'applied index is now lower than readState.Index' (duration: 596.034683ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:56:11.99225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195095414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-19T05:56:11.99224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.205712228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:56:11.992309Z","caller":"traceutil/trace.go:171","msg":"trace[1719571629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"1.195192085s","start":"2026-02-19T05:56:10.7971Z","end":"2026-02-19T05:56:11.992292Z","steps":["trace[1719571629] 'agreement among raft nodes before linearized reading' (duration: 1.194994881s)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:56:11.992313Z","caller":"traceutil/trace.go:171","msg":"trace[41901459] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"591.930502ms","start":"2026-02-19T05:56:11.400367Z","end":"2026-02-19T05:56:11.992298Z","steps":["trace[41901459] 'process raft request' (duration: 591.632515ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:56:11.992327Z","caller":"traceutil/trace.go:171","msg":"trace[2112100206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"1.205835161s","start":"2026-02-19T05:56:10.786468Z","end":"2026-02-19T05:56:11.992303Z","steps":["trace[2112100206] 'agreement among raft nodes before linearized reading' (duration: 1.205679728s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:11.99233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.545271986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-19T05:56:11.992349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:56:10.79709Z","time spent":"1.195250486s","remote":"127.0.0.1:35290","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":"info","ts":"2026-02-19T05:56:11.992369Z","caller":"traceutil/trace.go:171","msg":"trace[1568714058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"1.545364758s","start":"2026-02-19T05:56:10.446995Z","end":"2026-02-19T05:56:11.992359Z","steps":["trace[1568714058] 'agreement among raft nodes before linearized reading' (duration: 1.545198124s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:11.992369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:56:10.786451Z","time spent":"1.205910293s","remote":"127.0.0.1:59112","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-19T05:56:11.992401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:56:10.446975Z","time spent":"1.545418569s","remote":"127.0.0.1:35290","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-19T05:56:11.992412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:56:11.400346Z","time spent":"592.007024ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:56:14.184196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.400392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:56:14.184281Z","caller":"traceutil/trace.go:171","msg":"trace[1903672] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:567; }","duration":"195.545975ms","start":"2026-02-19T05:56:13.988716Z","end":"2026-02-19T05:56:14.184262Z","steps":["trace[1903672] 'range keys from in-memory index tree' (duration: 195.279659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:14.184378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.965125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-19T05:56:14.184385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.603406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-19T05:56:14.184446Z","caller":"traceutil/trace.go:171","msg":"trace[510126726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"181.709499ms","start":"2026-02-19T05:56:14.002722Z","end":"2026-02-19T05:56:14.184432Z","steps":["trace[510126726] 'range keys from in-memory index tree' (duration: 181.408381ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:56:14.184456Z","caller":"traceutil/trace.go:171","msg":"trace[1573011776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"178.040526ms","start":"2026-02-19T05:56:14.006381Z","end":"2026-02-19T05:56:14.184421Z","steps":["trace[1573011776] 'range keys from in-memory index tree' (duration: 177.585666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:14.184482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.764223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-19T05:56:14.184573Z","caller":"traceutil/trace.go:171","msg":"trace[1375059468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"187.929176ms","start":"2026-02-19T05:56:13.996619Z","end":"2026-02-19T05:56:14.184548Z","steps":["trace[1375059468] 'range keys from in-memory index tree' (duration: 187.556558ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:56:17.095278Z","caller":"traceutil/trace.go:171","msg":"trace[1538773101] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"117.219698ms","start":"2026-02-19T05:56:16.97804Z","end":"2026-02-19T05:56:17.09526Z","steps":["trace[1538773101] 'process raft request' (duration: 116.031922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:23.876255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.104394ms","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-19T05:56:23.87633Z","caller":"traceutil/trace.go:171","msg":"trace[379769269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:665; }","duration":"245.233067ms","start":"2026-02-19T05:56:23.631082Z","end":"2026-02-19T05:56:23.876315Z","steps":["trace[379769269] 'range keys from in-memory index tree' (duration: 244.958971ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:56:29.768027Z","caller":"traceutil/trace.go:171","msg":"trace[1539784390] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"203.030901ms","start":"2026-02-19T05:56:29.564973Z","end":"2026-02-19T05:56:29.768004Z","steps":["trace[1539784390] 'process raft request' (duration: 202.933449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:30.117427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.731437ms","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-19T05:56:30.117488Z","caller":"traceutil/trace.go:171","msg":"trace[1524813539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:710; }","duration":"217.845839ms","start":"2026-02-19T05:56:29.899628Z","end":"2026-02-19T05:56:30.117474Z","steps":["trace[1524813539] 'range keys from in-memory index tree' (duration: 217.339398ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:56:30.455498Z","caller":"traceutil/trace.go:171","msg":"trace[1219632960] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"186.665908ms","start":"2026-02-19T05:56:30.268812Z","end":"2026-02-19T05:56:30.455478Z","steps":["trace[1219632960] 'read index received' (duration: 186.505994ms)","trace[1219632960] 'applied index is now lower than readState.Index' (duration: 159.214µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:56:30.455721Z","caller":"traceutil/trace.go:171","msg":"trace[2011407929] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"334.151507ms","start":"2026-02-19T05:56:30.121532Z","end":"2026-02-19T05:56:30.455683Z","steps":["trace[2011407929] 'process raft request' (duration: 333.84168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:30.455781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.938634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-19T05:56:30.455863Z","caller":"traceutil/trace.go:171","msg":"trace[1353491439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:711; }","duration":"187.059407ms","start":"2026-02-19T05:56:30.268782Z","end":"2026-02-19T05:56:30.455842Z","steps":["trace[1353491439] 'agreement among raft nodes before linearized reading' (duration: 186.816341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:30.455907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.607573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-19T05:56:30.455884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.244728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-19T05:56:30.45594Z","caller":"traceutil/trace.go:171","msg":"trace[1971262453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"179.668114ms","start":"2026-02-19T05:56:30.276265Z","end":"2026-02-19T05:56:30.455933Z","steps":["trace[1971262453] 'agreement among raft nodes before linearized reading' (duration: 179.52703ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:56:30.455986Z","caller":"traceutil/trace.go:171","msg":"trace[877010312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:711; }","duration":"186.390642ms","start":"2026-02-19T05:56:30.269571Z","end":"2026-02-19T05:56:30.455961Z","steps":["trace[877010312] 'agreement among raft nodes before linearized reading' (duration: 186.078724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:30.45591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:56:30.12151Z","time spent":"334.305721ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:56:52.929591Z","caller":"traceutil/trace.go:171","msg":"trace[878939120] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"346.933956ms","start":"2026-02-19T05:56:52.58263Z","end":"2026-02-19T05:56:52.929564Z","steps":["trace[878939120] 'read index received' (duration: 346.855334ms)","trace[878939120] 'applied index is now lower than readState.Index' (duration: 77.442µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:56:52.929703Z","caller":"traceutil/trace.go:171","msg":"trace[1571624455] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"370.857357ms","start":"2026-02-19T05:56:52.55881Z","end":"2026-02-19T05:56:52.929667Z","steps":["trace[1571624455] 'process raft request' (duration: 370.603162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:52.929875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.230612ms","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-19T05:56:52.929921Z","caller":"traceutil/trace.go:171","msg":"trace[1109175825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"347.335194ms","start":"2026-02-19T05:56:52.582571Z","end":"2026-02-19T05:56:52.929907Z","steps":["trace[1109175825] 'agreement among raft nodes before linearized reading' (duration: 347.1301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:52.929969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:56:52.582548Z","time spent":"347.413395ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T05:56:52.929978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.017533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:56:52.930031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.032654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-19T05:56:52.930136Z","caller":"traceutil/trace.go:171","msg":"trace[345864098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"143.226738ms","start":"2026-02-19T05:56:52.786886Z","end":"2026-02-19T05:56:52.930113Z","steps":["trace[345864098] 'agreement among raft nodes before linearized reading' (duration: 142.980632ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:56:52.930184Z","caller":"traceutil/trace.go:171","msg":"trace[1224776184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"296.162057ms","start":"2026-02-19T05:56:52.633961Z","end":"2026-02-19T05:56:52.930123Z","steps":["trace[1224776184] 'agreement among raft nodes before linearized reading' (duration: 295.82217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:52.930173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.845078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-19T05:56:52.930369Z","caller":"traceutil/trace.go:171","msg":"trace[2024823091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"295.078743ms","start":"2026-02-19T05:56:52.635232Z","end":"2026-02-19T05:56:52.930311Z","steps":["trace[2024823091] 'agreement among raft nodes before linearized reading' (duration: 294.682294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:52.930941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:56:52.558793Z","time spent":"372.063064ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:56:53.314339Z","caller":"traceutil/trace.go:171","msg":"trace[1969358131] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"379.597449ms","start":"2026-02-19T05:56:52.934715Z","end":"2026-02-19T05:56:53.314313Z","steps":["trace[1969358131] 'process raft request' (duration: 349.649215ms)","trace[1969358131] 'compare' (duration: 29.843951ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:56:53.314729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:56:52.934698Z","time spent":"379.711371ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:56:53.375629Z","caller":"traceutil/trace.go:171","msg":"trace[1514087073] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:808; }","duration":"242.71356ms","start":"2026-02-19T05:56:53.132887Z","end":"2026-02-19T05:56:53.375601Z","steps":["trace[1514087073] 'read index received' (duration: 151.533839ms)","trace[1514087073] 'applied index is now lower than readState.Index' (duration: 91.178331ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:56:53.375754Z","caller":"traceutil/trace.go:171","msg":"trace[1021243751] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"439.880494ms","start":"2026-02-19T05:56:52.935851Z","end":"2026-02-19T05:56:53.375731Z","steps":["trace[1021243751] 'process raft request' (duration: 439.69185ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:56:53.375715Z","caller":"traceutil/trace.go:171","msg":"trace[1348696722] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"440.161511ms","start":"2026-02-19T05:56:52.935532Z","end":"2026-02-19T05:56:53.375694Z","steps":["trace[1348696722] 'process raft request' (duration: 439.871914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:53.375841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.922134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:56:53.375905Z","caller":"traceutil/trace.go:171","msg":"trace[36719241] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:781; }","duration":"243.039107ms","start":"2026-02-19T05:56:53.132851Z","end":"2026-02-19T05:56:53.37589Z","steps":["trace[36719241] 'agreement among raft nodes before linearized reading' (duration: 242.865073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:56:53.375896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:56:52.935825Z","time spent":"439.990927ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:56:53.375997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:56:52.935524Z","time spent":"440.354825ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:56:54.224482Z","caller":"traceutil/trace.go:171","msg":"trace[1059182244] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"270.189309ms","start":"2026-02-19T05:56:53.954268Z","end":"2026-02-19T05:56:54.224457Z","steps":["trace[1059182244] 'process raft request' (duration: 270.045806ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:06.518059Z","caller":"traceutil/trace.go:171","msg":"trace[450787839] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"224.628916ms","start":"2026-02-19T05:57:06.293402Z","end":"2026-02-19T05:57:06.518031Z","steps":["trace[450787839] 'process raft request' (duration: 224.472931ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:08.827695Z","caller":"traceutil/trace.go:171","msg":"trace[1140214771] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:907; }","duration":"149.685718ms","start":"2026-02-19T05:57:08.677977Z","end":"2026-02-19T05:57:08.827663Z","steps":["trace[1140214771] 'read index received' (duration: 149.474283ms)","trace[1140214771] 'applied index is now lower than readState.Index' (duration: 210.265µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:57:08.827732Z","caller":"traceutil/trace.go:171","msg":"trace[2127685046] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"302.336771ms","start":"2026-02-19T05:57:08.525364Z","end":"2026-02-19T05:57:08.827701Z","steps":["trace[2127685046] 'process raft request' (duration: 302.149067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:08.827904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.874172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:57:08.827962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:08.525337Z","time spent":"302.494625ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:57:08.828001Z","caller":"traceutil/trace.go:171","msg":"trace[1952567059] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:873; }","duration":"150.037145ms","start":"2026-02-19T05:57:08.677942Z","end":"2026-02-19T05:57:08.827979Z","steps":["trace[1952567059] 'agreement among raft nodes before linearized reading' (duration: 149.848761ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:09.284278Z","caller":"traceutil/trace.go:171","msg":"trace[1189714873] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"242.886263ms","start":"2026-02-19T05:57:09.041365Z","end":"2026-02-19T05:57:09.284251Z","steps":["trace[1189714873] 'read index received' (duration: 242.607607ms)","trace[1189714873] 'applied index is now lower than readState.Index' (duration: 277.776µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:57:09.284333Z","caller":"traceutil/trace.go:171","msg":"trace[830178301] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"342.056558ms","start":"2026-02-19T05:57:08.94224Z","end":"2026-02-19T05:57:09.284297Z","steps":["trace[830178301] 'process raft request' (duration: 341.878385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:09.284506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:08.942197Z","time spent":"342.210962ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:57:09.284521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.136358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-19T05:57:09.284618Z","caller":"traceutil/trace.go:171","msg":"trace[1638784220] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:874; }","duration":"243.294392ms","start":"2026-02-19T05:57:09.041311Z","end":"2026-02-19T05:57:09.284606Z","steps":["trace[1638784220] 'agreement among raft nodes before linearized reading' (duration: 243.036606ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:25.646252Z","caller":"traceutil/trace.go:171","msg":"trace[1607913088] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"127.520815ms","start":"2026-02-19T05:57:25.518706Z","end":"2026-02-19T05:57:25.646227Z","steps":["trace[1607913088] 'read index received' (duration: 50.736987ms)","trace[1607913088] 'applied index is now lower than readState.Index' (duration: 76.783048ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:57:25.646359Z","caller":"traceutil/trace.go:171","msg":"trace[898744921] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"185.013999ms","start":"2026-02-19T05:57:25.461338Z","end":"2026-02-19T05:57:25.646352Z","steps":["trace[898744921] 'process raft request' (duration: 108.097019ms)","trace[898744921] 'compare' (duration: 76.698446ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:57:25.646605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.227357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-19T05:57:25.646687Z","caller":"traceutil/trace.go:171","msg":"trace[660625698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:939; }","duration":"121.35611ms","start":"2026-02-19T05:57:25.52531Z","end":"2026-02-19T05:57:25.646666Z","steps":["trace[660625698] 'agreement among raft nodes before linearized reading' (duration: 121.154256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:25.646722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.001586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-19T05:57:25.646748Z","caller":"traceutil/trace.go:171","msg":"trace[559696061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:939; }","duration":"128.067927ms","start":"2026-02-19T05:57:25.518671Z","end":"2026-02-19T05:57:25.646739Z","steps":["trace[559696061] 'agreement among raft nodes before linearized reading' (duration: 127.961074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:26.814277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.192161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-19T05:57:26.814355Z","caller":"traceutil/trace.go:171","msg":"trace[383132024] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:941; }","duration":"810.325564ms","start":"2026-02-19T05:57:26.00401Z","end":"2026-02-19T05:57:26.814336Z","steps":["trace[383132024] 'range keys from in-memory index tree' (duration: 809.993117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:26.814404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:26.003983Z","time spent":"810.403336ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.74\" "} {"level":"info","ts":"2026-02-19T05:57:27.264008Z","caller":"traceutil/trace.go:171","msg":"trace[840681415] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"478.801785ms","start":"2026-02-19T05:57:26.78518Z","end":"2026-02-19T05:57:27.263981Z","steps":["trace[840681415] 'read index received' (duration: 478.680302ms)","trace[840681415] 'applied index is now lower than readState.Index' (duration: 120.043µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:57:27.264168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.969538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:57:27.264202Z","caller":"traceutil/trace.go:171","msg":"trace[933417015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"479.04602ms","start":"2026-02-19T05:57:26.785144Z","end":"2026-02-19T05:57:27.26419Z","steps":["trace[933417015] 'agreement among raft nodes before linearized reading' (duration: 478.952958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:27.264235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:26.785125Z","time spent":"479.102061ms","remote":"127.0.0.1:59112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T05:57:27.264503Z","caller":"traceutil/trace.go:171","msg":"trace[178120680] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"550.48819ms","start":"2026-02-19T05:57:26.713964Z","end":"2026-02-19T05:57:27.264452Z","steps":["trace[178120680] 'process raft request' (duration: 549.58215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:27.264879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:26.713932Z","time spent":"550.843717ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:57:27.291495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:26.815067Z","time spent":"476.426042ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-19T05:57:27.291723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.866536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-19T05:57:27.291753Z","caller":"traceutil/trace.go:171","msg":"trace[2064813091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:942; }","duration":"385.931627ms","start":"2026-02-19T05:57:26.905811Z","end":"2026-02-19T05:57:27.291743Z","steps":["trace[2064813091] 'agreement among raft nodes before linearized reading' (duration: 385.837895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:27.291771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:26.905793Z","time spent":"385.974768ms","remote":"127.0.0.1:35290","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-19T05:57:27.464021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.188593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:57:27.464237Z","caller":"traceutil/trace.go:171","msg":"trace[480792608] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"171.484165ms","start":"2026-02-19T05:57:27.292715Z","end":"2026-02-19T05:57:27.464199Z","steps":["trace[480792608] 'process raft request' (duration: 41.002465ms)","trace[480792608] 'compare' (duration: 129.942237ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:57:27.46455Z","caller":"traceutil/trace.go:171","msg":"trace[2136471733] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"167.780654ms","start":"2026-02-19T05:57:27.296742Z","end":"2026-02-19T05:57:27.464522Z","steps":["trace[2136471733] 'process raft request' (duration: 167.420696ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:27.784368Z","caller":"traceutil/trace.go:171","msg":"trace[1097281100] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"113.267343ms","start":"2026-02-19T05:57:27.671072Z","end":"2026-02-19T05:57:27.784339Z","steps":["trace[1097281100] 'read index received' (duration: 113.044098ms)","trace[1097281100] 'applied index is now lower than readState.Index' (duration: 222.325µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:57:27.784483Z","caller":"traceutil/trace.go:171","msg":"trace[1473567646] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"129.62293ms","start":"2026-02-19T05:57:27.654805Z","end":"2026-02-19T05:57:27.784428Z","steps":["trace[1473567646] 'process raft request' (duration: 129.375805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:27.784704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.59033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-19T05:57:27.784805Z","caller":"traceutil/trace.go:171","msg":"trace[76209610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:946; }","duration":"113.769055ms","start":"2026-02-19T05:57:27.671013Z","end":"2026-02-19T05:57:27.784782Z","steps":["trace[76209610] 'agreement among raft nodes before linearized reading' (duration: 113.454388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:28.052464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.927095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:57:28.052581Z","caller":"traceutil/trace.go:171","msg":"trace[1501878069] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"268.107275ms","start":"2026-02-19T05:57:27.784455Z","end":"2026-02-19T05:57:28.052562Z","steps":["trace[1501878069] 'read index received' (duration: 112.018506ms)","trace[1501878069] 'applied index is now lower than readState.Index' (duration: 156.087389ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:57:28.05275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.428139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T05:57:28.052786Z","caller":"traceutil/trace.go:171","msg":"trace[1016799134] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:947; }","duration":"365.498341ms","start":"2026-02-19T05:57:27.687278Z","end":"2026-02-19T05:57:28.052776Z","steps":["trace[1016799134] 'agreement among raft nodes before linearized reading' (duration: 365.354998ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:28.052746Z","caller":"traceutil/trace.go:171","msg":"trace[1638257843] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"317.560964ms","start":"2026-02-19T05:57:27.735128Z","end":"2026-02-19T05:57:28.052689Z","steps":["trace[1638257843] 'process raft request' (duration: 161.330033ms)","trace[1638257843] 'compare' (duration: 155.777122ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:57:28.052844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:27.687266Z","time spent":"365.550412ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-19T05:57:28.052937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:27.735102Z","time spent":"317.752239ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T05:57:28.052942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.554877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-19T05:57:28.053026Z","caller":"traceutil/trace.go:171","msg":"trace[318051677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:947; }","duration":"335.740401ms","start":"2026-02-19T05:57:27.717264Z","end":"2026-02-19T05:57:28.053005Z","steps":["trace[318051677] 'agreement among raft nodes before linearized reading' (duration: 335.448255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:28.053073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:27.717235Z","time spent":"335.829072ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T05:57:28.130666Z","caller":"traceutil/trace.go:171","msg":"trace[1844350179] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"340.838443ms","start":"2026-02-19T05:57:27.789798Z","end":"2026-02-19T05:57:28.130637Z","steps":["trace[1844350179] 'process raft request' (duration: 340.660928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:28.130813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:27.789767Z","time spent":"340.964595ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:57:28.242944Z","caller":"traceutil/trace.go:171","msg":"trace[795602751] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"186.146575ms","start":"2026-02-19T05:57:28.056744Z","end":"2026-02-19T05:57:28.24289Z","steps":["trace[795602751] 'process raft request' (duration: 113.99134ms)","trace[795602751] 'compare' (duration: 71.94298ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:57:29.510967Z","caller":"traceutil/trace.go:171","msg":"trace[1627499125] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"312.269737ms","start":"2026-02-19T05:57:29.198664Z","end":"2026-02-19T05:57:29.510934Z","steps":["trace[1627499125] 'read index received' (duration: 312.099884ms)","trace[1627499125] 'applied index is now lower than readState.Index' (duration: 168.713µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:57:29.510984Z","caller":"traceutil/trace.go:171","msg":"trace[1918781984] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"396.223732ms","start":"2026-02-19T05:57:29.114738Z","end":"2026-02-19T05:57:29.510962Z","steps":["trace[1918781984] 'process raft request' (duration: 396.045798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:29.51115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:29.114714Z","time spent":"396.357974ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T05:57:29.511212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.530854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:57:29.511258Z","caller":"traceutil/trace.go:171","msg":"trace[704128211] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:950; }","duration":"312.618605ms","start":"2026-02-19T05:57:29.198625Z","end":"2026-02-19T05:57:29.511244Z","steps":["trace[704128211] 'agreement among raft nodes before linearized reading' (duration: 312.462422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:29.511298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:29.198608Z","time spent":"312.680247ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-19T05:57:30.142354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.783476ms","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-19T05:57:30.142532Z","caller":"traceutil/trace.go:171","msg":"trace[46609459] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:950; }","duration":"506.985971ms","start":"2026-02-19T05:57:29.635519Z","end":"2026-02-19T05:57:30.142505Z","steps":["trace[46609459] 'range keys from in-memory index tree' (duration: 506.759596ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:30.142615Z","caller":"traceutil/trace.go:171","msg":"trace[26537394] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"626.640744ms","start":"2026-02-19T05:57:29.515953Z","end":"2026-02-19T05:57:30.142594Z","steps":["trace[26537394] 'process raft request' (duration: 530.398381ms)","trace[26537394] 'compare' (duration: 95.864755ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:57:30.142977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:29.515933Z","time spent":"626.728715ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:57:30.237659Z","caller":"traceutil/trace.go:171","msg":"trace[925677510] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"720.7765ms","start":"2026-02-19T05:57:29.516845Z","end":"2026-02-19T05:57:30.237622Z","steps":["trace[925677510] 'process raft request' (duration: 720.583105ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:30.23768Z","caller":"traceutil/trace.go:171","msg":"trace[1469345564] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:989; }","duration":"449.590608ms","start":"2026-02-19T05:57:29.78806Z","end":"2026-02-19T05:57:30.237651Z","steps":["trace[1469345564] 'read index received' (duration: 258.25916ms)","trace[1469345564] 'applied index is now lower than readState.Index' (duration: 191.330008ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:57:30.23778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:29.516839Z","time spent":"720.887422ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T05:57:30.2378Z","caller":"traceutil/trace.go:171","msg":"trace[1783632634] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"719.502241ms","start":"2026-02-19T05:57:29.518283Z","end":"2026-02-19T05:57:30.237786Z","steps":["trace[1783632634] 'process raft request' (duration: 719.251866ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:30.237896Z","caller":"traceutil/trace.go:171","msg":"trace[390660853] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"651.055947ms","start":"2026-02-19T05:57:29.586802Z","end":"2026-02-19T05:57:30.237858Z","steps":["trace[390660853] 'process raft request' (duration: 650.785421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:30.238048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:29.51826Z","time spent":"719.685374ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:57:30.238076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.979376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-19T05:57:30.238101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:29.586785Z","time spent":"651.208651ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:57:30.238122Z","caller":"traceutil/trace.go:171","msg":"trace[650760317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"450.080788ms","start":"2026-02-19T05:57:29.788029Z","end":"2026-02-19T05:57:30.238109Z","steps":["trace[650760317] 'agreement among raft nodes before linearized reading' (duration: 449.831143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:30.238158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:29.788011Z","time spent":"450.13958ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T05:57:30.513577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.244673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:57:30.51378Z","caller":"traceutil/trace.go:171","msg":"trace[1416434177] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"353.13382ms","start":"2026-02-19T05:57:30.160617Z","end":"2026-02-19T05:57:30.51375Z","steps":["trace[1416434177] 'process raft request' (duration: 206.641112ms)","trace[1416434177] 'compare' (duration: 146.11619ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:57:30.513878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:30.160574Z","time spent":"353.261803ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T05:57:30.514053Z","caller":"traceutil/trace.go:171","msg":"trace[782835764] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"276.269832ms","start":"2026-02-19T05:57:30.237748Z","end":"2026-02-19T05:57:30.514018Z","steps":["trace[782835764] 'read index received' (duration: 129.522768ms)","trace[782835764] 'applied index is now lower than readState.Index' (duration: 146.743914ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:57:30.514123Z","caller":"traceutil/trace.go:171","msg":"trace[1942376029] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"271.476668ms","start":"2026-02-19T05:57:30.242632Z","end":"2026-02-19T05:57:30.514109Z","steps":["trace[1942376029] 'process raft request' (duration: 271.429467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:30.514401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.971439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-19T05:57:30.514444Z","caller":"traceutil/trace.go:171","msg":"trace[1196020365] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:957; }","duration":"272.036151ms","start":"2026-02-19T05:57:30.242397Z","end":"2026-02-19T05:57:30.514433Z","steps":["trace[1196020365] 'agreement among raft nodes before linearized reading' (duration: 271.801395ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:30.514449Z","caller":"traceutil/trace.go:171","msg":"trace[1742306848] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"272.035451ms","start":"2026-02-19T05:57:30.242394Z","end":"2026-02-19T05:57:30.51443Z","steps":["trace[1742306848] 'process raft request' (duration: 271.296984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:30.51465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.609133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-19T05:57:30.514682Z","caller":"traceutil/trace.go:171","msg":"trace[1849022779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:957; }","duration":"267.680135ms","start":"2026-02-19T05:57:30.246994Z","end":"2026-02-19T05:57:30.514674Z","steps":["trace[1849022779] 'agreement among raft nodes before linearized reading' (duration: 267.595203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:30.515318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.264153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-19T05:57:30.515393Z","caller":"traceutil/trace.go:171","msg":"trace[400614593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:957; }","duration":"380.378086ms","start":"2026-02-19T05:57:30.135Z","end":"2026-02-19T05:57:30.515378Z","steps":["trace[400614593] 'agreement among raft nodes before linearized reading' (duration: 379.176689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:30.515916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:30.134981Z","time spent":"380.919847ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-19T05:57:30.866042Z","caller":"traceutil/trace.go:171","msg":"trace[704744302] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"347.104759ms","start":"2026-02-19T05:57:30.518824Z","end":"2026-02-19T05:57:30.865929Z","steps":["trace[704744302] 'process raft request' (duration: 346.890595ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:30.866539Z","caller":"traceutil/trace.go:171","msg":"trace[687068431] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"346.969616ms","start":"2026-02-19T05:57:30.519551Z","end":"2026-02-19T05:57:30.86652Z","steps":["trace[687068431] 'process raft request' (duration: 346.616188ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:30.866325Z","caller":"traceutil/trace.go:171","msg":"trace[680947674] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"347.011197ms","start":"2026-02-19T05:57:30.519282Z","end":"2026-02-19T05:57:30.866293Z","steps":["trace[680947674] 'process raft request' (duration: 346.603268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:30.866709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:30.519276Z","time spent":"347.347034ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:57:30.866683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:30.518816Z","time spent":"347.668411ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:57:30.867007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:30.519545Z","time spent":"347.029937ms","remote":"127.0.0.1:35124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T05:57:31.604572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.17026ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T05:57:32.508517Z","caller":"traceutil/trace.go:171","msg":"trace[488017943] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"266.792554ms","start":"2026-02-19T05:57:32.241695Z","end":"2026-02-19T05:57:32.508488Z","steps":["trace[488017943] 'read index received' (duration: 266.654521ms)","trace[488017943] 'applied index is now lower than readState.Index' (duration: 136.893µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:57:32.508663Z","caller":"traceutil/trace.go:171","msg":"trace[1251116028] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"357.585526ms","start":"2026-02-19T05:57:32.15102Z","end":"2026-02-19T05:57:32.508605Z","steps":["trace[1251116028] 'process raft request' (duration: 357.27081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:32.508735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.016969ms","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-19T05:57:32.508775Z","caller":"traceutil/trace.go:171","msg":"trace[1876446950] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"267.117241ms","start":"2026-02-19T05:57:32.241646Z","end":"2026-02-19T05:57:32.508763Z","steps":["trace[1876446950] 'agreement among raft nodes before linearized reading' (duration: 266.949237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:32.509115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:32.151004Z","time spent":"357.77107ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:57:32.946747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.183178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:57:32.946891Z","caller":"traceutil/trace.go:171","msg":"trace[827333921] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"161.299492ms","start":"2026-02-19T05:57:32.785566Z","end":"2026-02-19T05:57:32.946866Z","steps":["trace[827333921] 'read index received' (duration: 48.291µs)","trace[827333921] 'applied index is now lower than readState.Index' (duration: 161.249421ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:57:32.947041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.476646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:57:32.947093Z","caller":"traceutil/trace.go:171","msg":"trace[1509114274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"161.566088ms","start":"2026-02-19T05:57:32.785513Z","end":"2026-02-19T05:57:32.947079Z","steps":["trace[1509114274] 'agreement among raft nodes before linearized reading' (duration: 161.480476ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:32.947108Z","caller":"traceutil/trace.go:171","msg":"trace[2021667653] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"344.949711ms","start":"2026-02-19T05:57:32.602132Z","end":"2026-02-19T05:57:32.947082Z","steps":["trace[2021667653] 'process raft request' (duration: 135.298364ms)","trace[2021667653] 'compare' (duration: 209.048274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:57:32.947207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:57:32.602117Z","time spent":"345.037714ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:57:35.057616Z","caller":"traceutil/trace.go:171","msg":"trace[916004041] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"102.729193ms","start":"2026-02-19T05:57:34.954864Z","end":"2026-02-19T05:57:35.057593Z","steps":["trace[916004041] 'process raft request' (duration: 102.550089ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:38.707922Z","caller":"traceutil/trace.go:171","msg":"trace[1087470928] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"163.267385ms","start":"2026-02-19T05:57:38.544633Z","end":"2026-02-19T05:57:38.707901Z","steps":["trace[1087470928] 'process raft request' (duration: 163.136022ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:40.856654Z","caller":"traceutil/trace.go:171","msg":"trace[568042299] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"143.594765ms","start":"2026-02-19T05:57:40.713032Z","end":"2026-02-19T05:57:40.856626Z","steps":["trace[568042299] 'process raft request' (duration: 143.404731ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:57:40.899577Z","caller":"traceutil/trace.go:171","msg":"trace[1625109429] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1041; }","duration":"163.026614ms","start":"2026-02-19T05:57:40.736531Z","end":"2026-02-19T05:57:40.899558Z","steps":["trace[1625109429] 'read index received' (duration: 119.991885ms)","trace[1625109429] 'applied index is now lower than readState.Index' (duration: 43.033979ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:57:40.899706Z","caller":"traceutil/trace.go:171","msg":"trace[856899233] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"185.375735ms","start":"2026-02-19T05:57:40.714287Z","end":"2026-02-19T05:57:40.899662Z","steps":["trace[856899233] 'process raft request' (duration: 185.171051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:40.899769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.831169ms","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-19T05:57:40.899804Z","caller":"traceutil/trace.go:171","msg":"trace[26725980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"157.892011ms","start":"2026-02-19T05:57:40.741903Z","end":"2026-02-19T05:57:40.899795Z","steps":["trace[26725980] 'agreement among raft nodes before linearized reading' (duration: 157.786769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:40.899741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.197167ms","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-19T05:57:40.899873Z","caller":"traceutil/trace.go:171","msg":"trace[11536606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"163.379092ms","start":"2026-02-19T05:57:40.73649Z","end":"2026-02-19T05:57:40.899869Z","steps":["trace[11536606] 'agreement among raft nodes before linearized reading' (duration: 163.153007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:57:40.899959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.681128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:57:40.900085Z","caller":"traceutil/trace.go:171","msg":"trace[717845082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"114.766069ms","start":"2026-02-19T05:57:40.785224Z","end":"2026-02-19T05:57:40.89999Z","steps":["trace[717845082] 'agreement among raft nodes before linearized reading' (duration: 114.678197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:59:41.697633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.211887ms","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-19T05:59:41.697749Z","caller":"traceutil/trace.go:171","msg":"trace[595885554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1513; }","duration":"100.37285ms","start":"2026-02-19T05:59:41.597354Z","end":"2026-02-19T05:59:41.697727Z","steps":["trace[595885554] 'range keys from in-memory index tree' (duration: 100.066894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T06:00:18.327084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.208104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-19T06:00:18.327143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.292837ms","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-19T06:00:18.327188Z","caller":"traceutil/trace.go:171","msg":"trace[267231234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1662; }","duration":"150.369078ms","start":"2026-02-19T06:00:18.176797Z","end":"2026-02-19T06:00:18.327166Z","steps":["trace[267231234] 'range keys from in-memory index tree' (duration: 150.03661ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T06:00:18.327247Z","caller":"traceutil/trace.go:171","msg":"trace[444667929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1662; }","duration":"150.414929ms","start":"2026-02-19T06:00:18.176809Z","end":"2026-02-19T06:00:18.327224Z","steps":["trace[444667929] 'range keys from in-memory index tree' (duration: 150.039441ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T06:00:18.510287Z","caller":"traceutil/trace.go:171","msg":"trace[1951804474] transaction","detail":"{read_only:false; response_revision:1666; number_of_response:1; }","duration":"176.613907ms","start":"2026-02-19T06:00:18.333646Z","end":"2026-02-19T06:00:18.51026Z","steps":["trace[1951804474] 'process raft request' (duration: 176.366382ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T06:00:18.510289Z","caller":"traceutil/trace.go:171","msg":"trace[329370599] transaction","detail":"{read_only:false; response_revision:1665; number_of_response:1; }","duration":"177.045278ms","start":"2026-02-19T06:00:18.33321Z","end":"2026-02-19T06:00:18.510256Z","steps":["trace[329370599] 'process raft request' (duration: 176.750432ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T06:00:18.510367Z","caller":"traceutil/trace.go:171","msg":"trace[2063059798] transaction","detail":"{read_only:false; response_revision:1664; number_of_response:1; }","duration":"178.723834ms","start":"2026-02-19T06:00:18.331628Z","end":"2026-02-19T06:00:18.510352Z","steps":["trace[2063059798] 'process raft request' (duration: 148.18444ms)","trace[2063059798] 'compare' (duration: 29.898609ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T06:00:31.75523Z","caller":"traceutil/trace.go:171","msg":"trace[1020581965] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"140.239195ms","start":"2026-02-19T06:00:31.614969Z","end":"2026-02-19T06:00:31.755209Z","steps":["trace[1020581965] 'process raft request' (duration: 140.110092ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T06:00:52.084594Z","caller":"traceutil/trace.go:171","msg":"trace[1352917319] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"297.744262ms","start":"2026-02-19T06:00:51.786825Z","end":"2026-02-19T06:00:52.08457Z","steps":["trace[1352917319] 'read index received' (duration: 297.618769ms)","trace[1352917319] 'applied index is now lower than readState.Index' (duration: 124.203µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T06:00:52.084651Z","caller":"traceutil/trace.go:171","msg":"trace[396804868] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"431.151975ms","start":"2026-02-19T06:00:51.653463Z","end":"2026-02-19T06:00:52.084615Z","steps":["trace[396804868] 'process raft request' (duration: 430.94276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T06:00:52.084764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.906835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T06:00:52.084823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T06:00:51.653441Z","time spent":"431.288298ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T06:00:52.084969Z","caller":"traceutil/trace.go:171","msg":"trace[107951637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1822; }","duration":"298.10482ms","start":"2026-02-19T06:00:51.786787Z","end":"2026-02-19T06:00:52.084892Z","steps":["trace[107951637] 'agreement among raft nodes before linearized reading' (duration: 297.883975ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T06:00:52.124001Z","caller":"traceutil/trace.go:171","msg":"trace[1804930295] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"224.808513ms","start":"2026-02-19T06:00:51.899172Z","end":"2026-02-19T06:00:52.12398Z","steps":["trace[1804930295] 'process raft request' (duration: 223.619946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T06:00:52.124295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.256155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-19T06:00:52.124457Z","caller":"traceutil/trace.go:171","msg":"trace[1526316019] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1823; }","duration":"333.46221ms","start":"2026-02-19T06:00:51.79095Z","end":"2026-02-19T06:00:52.124413Z","steps":["trace[1526316019] 'agreement among raft nodes before linearized reading' (duration: 332.99817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T06:00:52.124521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T06:00:51.790943Z","time spent":"333.564872ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-19T06:00:58.995031Z","caller":"traceutil/trace.go:171","msg":"trace[1330217675] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"525.454963ms","start":"2026-02-19T06:00:58.469553Z","end":"2026-02-19T06:00:58.995008Z","steps":["trace[1330217675] 'process raft request' (duration: 525.30397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T06:00:58.995209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T06:00:58.469527Z","time spent":"525.574786ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T06:00:59.042766Z","caller":"traceutil/trace.go:171","msg":"trace[675168724] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"570.456987ms","start":"2026-02-19T06:00:58.472282Z","end":"2026-02-19T06:00:59.042739Z","steps":["trace[675168724] 'process raft request' (duration: 570.262023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T06:00:59.042986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T06:00:58.472263Z","time spent":"570.627631ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T06:00:59.043041Z","caller":"traceutil/trace.go:171","msg":"trace[919132279] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"569.403835ms","start":"2026-02-19T06:00:58.473611Z","end":"2026-02-19T06:00:59.043015Z","steps":["trace[919132279] 'process raft request' (duration: 569.078917ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T06:00:59.043258Z","caller":"traceutil/trace.go:171","msg":"trace[14733831] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1937; }","duration":"300.554238ms","start":"2026-02-19T06:00:58.742505Z","end":"2026-02-19T06:00:59.04306Z","steps":["trace[14733831] 'read index received' (duration: 252.358473ms)","trace[14733831] 'applied index is now lower than readState.Index' (duration: 48.193445ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T06:00:59.043275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T06:00:58.473587Z","time spent":"569.549119ms","remote":"127.0.0.1:35290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T06:00:59.043294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.748982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-19T06:00:59.043347Z","caller":"traceutil/trace.go:171","msg":"trace[928141820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1858; }","duration":"300.881065ms","start":"2026-02-19T06:00:58.742449Z","end":"2026-02-19T06:00:59.04333Z","steps":["trace[928141820] 'agreement among raft nodes before linearized reading' (duration: 300.685571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T06:00:59.04339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T06:00:58.742431Z","time spent":"300.950446ms","remote":"127.0.0.1:35290","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-19T06:00:59.04348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.19855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T06:00:59.04355Z","caller":"traceutil/trace.go:171","msg":"trace[798667930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1858; }","duration":"257.314892ms","start":"2026-02-19T06:00:58.786222Z","end":"2026-02-19T06:00:59.043537Z","steps":["trace[798667930] 'agreement among raft nodes before linearized reading' (duration: 257.18666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T06:00:59.043598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.71265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-02-19T06:00:59.043606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.240172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-19T06:00:59.04366Z","caller":"traceutil/trace.go:171","msg":"trace[1029856836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1858; }","duration":"181.336855ms","start":"2026-02-19T06:00:58.862306Z","end":"2026-02-19T06:00:59.043643Z","steps":["trace[1029856836] 'agreement among raft nodes before linearized reading' (duration: 181.174481ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T06:00:59.04369Z","caller":"traceutil/trace.go:171","msg":"trace[464895162] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1858; }","duration":"122.830222ms","start":"2026-02-19T06:00:58.920845Z","end":"2026-02-19T06:00:59.043675Z","steps":["trace[464895162] 'agreement among raft nodes before linearized reading' (duration: 122.581527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T06:00:59.043775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.496131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-19T06:00:59.04387Z","caller":"traceutil/trace.go:171","msg":"trace[1720073503] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1858; }","duration":"163.654694ms","start":"2026-02-19T06:00:58.880198Z","end":"2026-02-19T06:00:59.043853Z","steps":["trace[1720073503] 'agreement among raft nodes before linearized reading' (duration: 163.264796ms)"],"step_count":1}