{"level":"warn","ts":"2026-02-07T01:49:18.364918Z","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-07T01:49:18.365102Z","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-07T01:49:18.365184Z","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-07T01:49:18.36521Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-07T01:49:18.365256Z","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-07T01:49:18.366063Z","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-07T01:49:18.376576Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.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-07T01:49:18.391067Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.261485ms"} {"level":"info","ts":"2026-02-07T01:49:18.500118Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-02-07T01:49:18.500222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T01:49:18.500244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-02-07T01:49:18.500255Z","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-07T01:49:18.500262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-02-07T01:49:18.500309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-02-07T01:49:18.511685Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T01:49:18.519558Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T01:49:18.522738Z","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-07T01:49:18.531197Z","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-07T01:49:18.548758Z","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-07T01:49:18.548992Z","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-07T01:49:18.549056Z","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-07T01:49:18.549088Z","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-07T01:49:18.564706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-02-07T01:49:18.564838Z","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-07T01:49:18.565153Z","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-07T01:49:18.565312Z","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-07T01:49:18.565395Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T01:49:18.565528Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-07T01:49:18.566993Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-07T01:49:19.501732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T01:49:19.501779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T01:49:19.501804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-02-07T01:49:19.501816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-02-07T01:49:19.501821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-07T01:49:19.501851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-02-07T01:49:19.50186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-07T01:49:19.503863Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T01:49:19.504689Z","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-07T01:49:19.504934Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T01:49:19.505278Z","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-07T01:49:19.505411Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T01:49:19.505452Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T01:49:19.505496Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T01:49:19.505549Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T01:49:19.505556Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T01:49:19.507085Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-07T01:49:19.514426Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-02-07T01:49:48.759383Z","caller":"traceutil/trace.go:171","msg":"trace[1251852591] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"125.720792ms","start":"2026-02-07T01:49:48.63365Z","end":"2026-02-07T01:49:48.759371Z","steps":["trace[1251852591] 'read index received' (duration: 125.612444ms)","trace[1251852591] 'applied index is now lower than readState.Index' (duration: 107.888µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:49:48.759471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.807668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:48.759493Z","caller":"traceutil/trace.go:171","msg":"trace[1037432455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"125.864082ms","start":"2026-02-07T01:49:48.633624Z","end":"2026-02-07T01:49:48.759488Z","steps":["trace[1037432455] 'agreement among raft nodes before linearized reading' (duration: 125.807638ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:49:48.759662Z","caller":"traceutil/trace.go:171","msg":"trace[1373909735] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"197.324528ms","start":"2026-02-07T01:49:48.562333Z","end":"2026-02-07T01:49:48.759657Z","steps":["trace[1373909735] 'process raft request' (duration: 196.960412ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:49:50.70682Z","caller":"traceutil/trace.go:171","msg":"trace[1406191610] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"253.348298ms","start":"2026-02-07T01:49:50.453455Z","end":"2026-02-07T01:49:50.706804Z","steps":["trace[1406191610] 'process raft request' (duration: 253.001784ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:49:51.278417Z","caller":"traceutil/trace.go:171","msg":"trace[592752109] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"235.144819ms","start":"2026-02-07T01:49:51.043259Z","end":"2026-02-07T01:49:51.278403Z","steps":["trace[592752109] 'process raft request' (duration: 234.87496ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:49:51.283351Z","caller":"traceutil/trace.go:171","msg":"trace[672667867] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:535; }","duration":"231.427048ms","start":"2026-02-07T01:49:51.051915Z","end":"2026-02-07T01:49:51.283342Z","steps":["trace[672667867] 'read index received' (duration: 226.380454ms)","trace[672667867] 'applied index is now lower than readState.Index' (duration: 5.046194ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:49:51.283495Z","caller":"traceutil/trace.go:171","msg":"trace[352759485] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"239.930115ms","start":"2026-02-07T01:49:51.043559Z","end":"2026-02-07T01:49:51.283489Z","steps":["trace[352759485] 'process raft request' (duration: 239.754283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:51.288576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.568649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:51.2886Z","caller":"traceutil/trace.go:171","msg":"trace[1228179722] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:517; }","duration":"236.698569ms","start":"2026-02-07T01:49:51.051894Z","end":"2026-02-07T01:49:51.288593Z","steps":["trace[1228179722] 'agreement among raft nodes before linearized reading' (duration: 236.57189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:51.288723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.841162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-07T01:49:51.288736Z","caller":"traceutil/trace.go:171","msg":"trace[1613791832] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"208.884055ms","start":"2026-02-07T01:49:51.079848Z","end":"2026-02-07T01:49:51.288732Z","steps":["trace[1613791832] 'agreement among raft nodes before linearized reading' (duration: 208.828481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:51.2889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.990084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-07T01:49:51.288913Z","caller":"traceutil/trace.go:171","msg":"trace[938469332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:517; }","duration":"138.025577ms","start":"2026-02-07T01:49:51.150884Z","end":"2026-02-07T01:49:51.28891Z","steps":["trace[938469332] 'agreement among raft nodes before linearized reading' (duration: 137.987994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:51.288981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.639573ms","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-07T01:49:51.288992Z","caller":"traceutil/trace.go:171","msg":"trace[1252252517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"188.671655ms","start":"2026-02-07T01:49:51.100317Z","end":"2026-02-07T01:49:51.288989Z","steps":["trace[1252252517] 'agreement among raft nodes before linearized reading' (duration: 188.638792ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:49:57.877891Z","caller":"traceutil/trace.go:171","msg":"trace[52780367] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"458.312329ms","start":"2026-02-07T01:49:57.41956Z","end":"2026-02-07T01:49:57.877873Z","steps":["trace[52780367] 'process raft request' (duration: 458.19044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.878294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:49:57.41955Z","time spent":"458.403566ms","remote":"127.0.0.1:36168","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-07T01:49:57.87855Z","caller":"traceutil/trace.go:171","msg":"trace[1478054041] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"453.760136ms","start":"2026-02-07T01:49:57.424784Z","end":"2026-02-07T01:49:57.878544Z","steps":["trace[1478054041] 'process raft request' (duration: 453.588384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.878589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:49:57.42477Z","time spent":"453.799309ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:49:57.880428Z","caller":"traceutil/trace.go:171","msg":"trace[474031871] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"455.573245ms","start":"2026-02-07T01:49:57.424843Z","end":"2026-02-07T01:49:57.880416Z","steps":["trace[474031871] 'read index received' (duration: 453.039925ms)","trace[474031871] 'applied index is now lower than readState.Index' (duration: 2.53276ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:49:57.880647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.78611ms","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-07T01:49:57.880674Z","caller":"traceutil/trace.go:171","msg":"trace[286673441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:565; }","duration":"455.843214ms","start":"2026-02-07T01:49:57.424825Z","end":"2026-02-07T01:49:57.880668Z","steps":["trace[286673441] 'agreement among raft nodes before linearized reading' (duration: 455.729106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.880692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:49:57.42482Z","time spent":"455.868405ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T01:49:57.880792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.798048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.880805Z","caller":"traceutil/trace.go:171","msg":"trace[2071705656] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:565; }","duration":"435.831811ms","start":"2026-02-07T01:49:57.444968Z","end":"2026-02-07T01:49:57.8808Z","steps":["trace[2071705656] 'agreement among raft nodes before linearized reading' (duration: 435.80755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.88082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:49:57.444939Z","time spent":"435.877135ms","remote":"127.0.0.1:38674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-02-07T01:49:57.880907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.303657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.880922Z","caller":"traceutil/trace.go:171","msg":"trace[682370447] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:565; }","duration":"124.324848ms","start":"2026-02-07T01:49:57.756591Z","end":"2026-02-07T01:49:57.880916Z","steps":["trace[682370447] 'agreement among raft nodes before linearized reading' (duration: 124.303846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.88099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.771949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.881004Z","caller":"traceutil/trace.go:171","msg":"trace[1005097125] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:565; }","duration":"124.797182ms","start":"2026-02-07T01:49:57.756203Z","end":"2026-02-07T01:49:57.881Z","steps":["trace[1005097125] 'agreement among raft nodes before linearized reading' (duration: 124.77385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.88106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.017166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.881071Z","caller":"traceutil/trace.go:171","msg":"trace[1685643171] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:565; }","duration":"125.038728ms","start":"2026-02-07T01:49:57.756029Z","end":"2026-02-07T01:49:57.881068Z","steps":["trace[1685643171] 'agreement among raft nodes before linearized reading' (duration: 125.016487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.881126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.860232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.881141Z","caller":"traceutil/trace.go:171","msg":"trace[497929780] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:565; }","duration":"130.879383ms","start":"2026-02-07T01:49:57.750256Z","end":"2026-02-07T01:49:57.881136Z","steps":["trace[497929780] 'agreement among raft nodes before linearized reading' (duration: 130.857182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.8812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.320724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.881226Z","caller":"traceutil/trace.go:171","msg":"trace[1107801064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"250.347596ms","start":"2026-02-07T01:49:57.630871Z","end":"2026-02-07T01:49:57.881218Z","steps":["trace[1107801064] 'agreement among raft nodes before linearized reading' (duration: 250.289952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.881228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.968894ms","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-07T01:49:57.881245Z","caller":"traceutil/trace.go:171","msg":"trace[1012740293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"365.006267ms","start":"2026-02-07T01:49:57.516234Z","end":"2026-02-07T01:49:57.88124Z","steps":["trace[1012740293] 'agreement among raft nodes before linearized reading' (duration: 364.961954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.881261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:49:57.516219Z","time spent":"365.037549ms","remote":"127.0.0.1:36168","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-07T01:49:57.881335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.344309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-07T01:49:57.881339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.083708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.881347Z","caller":"traceutil/trace.go:171","msg":"trace[2047477645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:565; }","duration":"373.396343ms","start":"2026-02-07T01:49:57.507947Z","end":"2026-02-07T01:49:57.881343Z","steps":["trace[2047477645] 'agreement among raft nodes before linearized reading' (duration: 373.36109ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:49:57.881355Z","caller":"traceutil/trace.go:171","msg":"trace[866750816] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:565; }","duration":"131.106339ms","start":"2026-02-07T01:49:57.750244Z","end":"2026-02-07T01:49:57.88135Z","steps":["trace[866750816] 'agreement among raft nodes before linearized reading' (duration: 131.066897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.881359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:49:57.507929Z","time spent":"373.428095ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T01:49:57.881414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.37019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.881427Z","caller":"traceutil/trace.go:171","msg":"trace[990435319] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:565; }","duration":"436.383101ms","start":"2026-02-07T01:49:57.445039Z","end":"2026-02-07T01:49:57.881422Z","steps":["trace[990435319] 'agreement among raft nodes before linearized reading' (duration: 436.363159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.881439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:49:57.445012Z","time spent":"436.422834ms","remote":"127.0.0.1:38674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-07T01:49:57.881492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.478537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.881503Z","caller":"traceutil/trace.go:171","msg":"trace[108006967] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:565; }","duration":"436.496278ms","start":"2026-02-07T01:49:57.445003Z","end":"2026-02-07T01:49:57.881499Z","steps":["trace[108006967] 'agreement among raft nodes before linearized reading' (duration: 436.478157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.881514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:49:57.444999Z","time spent":"436.51218ms","remote":"127.0.0.1:38674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-07T01:49:57.88182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.582823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.88184Z","caller":"traceutil/trace.go:171","msg":"trace[1290199536] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:565; }","duration":"131.617986ms","start":"2026-02-07T01:49:57.750214Z","end":"2026-02-07T01:49:57.881832Z","steps":["trace[1290199536] 'agreement among raft nodes before linearized reading' (duration: 131.584063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.881839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.676295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.881857Z","caller":"traceutil/trace.go:171","msg":"trace[1932897052] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:565; }","duration":"154.700507ms","start":"2026-02-07T01:49:57.727151Z","end":"2026-02-07T01:49:57.881852Z","steps":["trace[1932897052] 'agreement among raft nodes before linearized reading' (duration: 154.672825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.881939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.77531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-07T01:49:57.881929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.786153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.881953Z","caller":"traceutil/trace.go:171","msg":"trace[660011003] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:565; }","duration":"143.796342ms","start":"2026-02-07T01:49:57.738152Z","end":"2026-02-07T01:49:57.881948Z","steps":["trace[660011003] 'agreement among raft nodes before linearized reading' (duration: 143.77297ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:49:57.881964Z","caller":"traceutil/trace.go:171","msg":"trace[323725361] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:565; }","duration":"154.840537ms","start":"2026-02-07T01:49:57.727118Z","end":"2026-02-07T01:49:57.881959Z","steps":["trace[323725361] 'agreement among raft nodes before linearized reading' (duration: 154.796584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.882023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.884048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.882039Z","caller":"traceutil/trace.go:171","msg":"trace[1374203697] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:565; }","duration":"143.9049ms","start":"2026-02-07T01:49:57.738128Z","end":"2026-02-07T01:49:57.882033Z","steps":["trace[1374203697] 'agreement among raft nodes before linearized reading' (duration: 143.881128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.882099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.984105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.882112Z","caller":"traceutil/trace.go:171","msg":"trace[1011174659] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:565; }","duration":"144.013167ms","start":"2026-02-07T01:49:57.738094Z","end":"2026-02-07T01:49:57.882108Z","steps":["trace[1011174659] 'agreement among raft nodes before linearized reading' (duration: 143.990726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:57.882178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.986477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:57.882191Z","caller":"traceutil/trace.go:171","msg":"trace[1613287843] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:565; }","duration":"155.001908ms","start":"2026-02-07T01:49:57.727185Z","end":"2026-02-07T01:49:57.882187Z","steps":["trace[1613287843] 'agreement among raft nodes before linearized reading' (duration: 154.979016ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:49:58.938518Z","caller":"traceutil/trace.go:171","msg":"trace[483252430] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"304.996678ms","start":"2026-02-07T01:49:58.633505Z","end":"2026-02-07T01:49:58.938501Z","steps":["trace[483252430] 'read index received' (duration: 304.817185ms)","trace[483252430] 'applied index is now lower than readState.Index' (duration: 178.803µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:49:58.938591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.069793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:49:58.938605Z","caller":"traceutil/trace.go:171","msg":"trace[873273835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"305.122987ms","start":"2026-02-07T01:49:58.633478Z","end":"2026-02-07T01:49:58.938601Z","steps":["trace[873273835] 'agreement among raft nodes before linearized reading' (duration: 305.080964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:58.938623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:49:58.633467Z","time spent":"305.15266ms","remote":"127.0.0.1:35862","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-07T01:49:58.93878Z","caller":"traceutil/trace.go:171","msg":"trace[210751290] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"737.169024ms","start":"2026-02-07T01:49:58.201605Z","end":"2026-02-07T01:49:58.938774Z","steps":["trace[210751290] 'process raft request' (duration: 736.722002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:49:58.938824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:49:58.201585Z","time spent":"737.207536ms","remote":"127.0.0.1:36070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:50:00.002665Z","caller":"traceutil/trace.go:171","msg":"trace[2058054751] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"114.929058ms","start":"2026-02-07T01:49:59.887721Z","end":"2026-02-07T01:50:00.00265Z","steps":["trace[2058054751] 'read index received' (duration: 114.766926ms)","trace[2058054751] 'applied index is now lower than readState.Index' (duration: 161.292µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:50:00.002739Z","caller":"traceutil/trace.go:171","msg":"trace[1946784756] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"117.650472ms","start":"2026-02-07T01:49:59.885084Z","end":"2026-02-07T01:50:00.002734Z","steps":["trace[1946784756] 'process raft request' (duration: 117.471059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:50:00.002883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.153904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T01:50:00.002898Z","caller":"traceutil/trace.go:171","msg":"trace[2065411689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"115.211958ms","start":"2026-02-07T01:49:59.887681Z","end":"2026-02-07T01:50:00.002893Z","steps":["trace[2065411689] 'agreement among raft nodes before linearized reading' (duration: 115.136313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:50:00.00308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.067279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-07T01:50:00.003141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.553767ms","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-07T01:50:00.003147Z","caller":"traceutil/trace.go:171","msg":"trace[2078866999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"108.153724ms","start":"2026-02-07T01:49:59.89498Z","end":"2026-02-07T01:50:00.003134Z","steps":["trace[2078866999] 'agreement among raft nodes before linearized reading' (duration: 107.970971ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:50:00.003157Z","caller":"traceutil/trace.go:171","msg":"trace[2027724283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:573; }","duration":"104.5784ms","start":"2026-02-07T01:49:59.898574Z","end":"2026-02-07T01:50:00.003152Z","steps":["trace[2027724283] 'agreement among raft nodes before linearized reading' (duration: 104.535546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:50:00.003295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.444701ms","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-07T01:50:00.003314Z","caller":"traceutil/trace.go:171","msg":"trace[332402179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"105.466003ms","start":"2026-02-07T01:49:59.897842Z","end":"2026-02-07T01:50:00.003308Z","steps":["trace[332402179] 'agreement among raft nodes before linearized reading' (duration: 105.398078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:50:00.846314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.209507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:50:00.846379Z","caller":"traceutil/trace.go:171","msg":"trace[2098869208] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"838.906537ms","start":"2026-02-07T01:50:00.00746Z","end":"2026-02-07T01:50:00.846366Z","steps":["trace[2098869208] 'process raft request' (duration: 719.478674ms)","trace[2098869208] 'compare' (duration: 119.115591ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:50:00.84641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:50:00.007447Z","time spent":"838.94941ms","remote":"127.0.0.1:36168","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-07T01:50:00.88168Z","caller":"traceutil/trace.go:171","msg":"trace[335874012] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:595; }","duration":"250.492327ms","start":"2026-02-07T01:50:00.631173Z","end":"2026-02-07T01:50:00.881665Z","steps":["trace[335874012] 'read index received' (duration: 95.773303ms)","trace[335874012] 'applied index is now lower than readState.Index' (duration: 154.718534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:50:00.881777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.584783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:50:00.881827Z","caller":"traceutil/trace.go:171","msg":"trace[1452353275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"250.671159ms","start":"2026-02-07T01:50:00.631144Z","end":"2026-02-07T01:50:00.881815Z","steps":["trace[1452353275] 'agreement among raft nodes before linearized reading' (duration: 250.580013ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:50:00.882289Z","caller":"traceutil/trace.go:171","msg":"trace[2005664185] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"874.721136ms","start":"2026-02-07T01:50:00.007546Z","end":"2026-02-07T01:50:00.882267Z","steps":["trace[2005664185] 'process raft request' (duration: 874.013645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:50:00.882441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:50:00.007527Z","time spent":"874.850014ms","remote":"127.0.0.1:36168","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-07T01:50:00.882527Z","caller":"traceutil/trace.go:171","msg":"trace[972166393] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"871.850451ms","start":"2026-02-07T01:50:00.010657Z","end":"2026-02-07T01:50:00.882507Z","steps":["trace[972166393] 'process raft request' (duration: 870.990309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:50:00.882597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:50:00.010651Z","time spent":"871.897854ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:50:00.882677Z","caller":"traceutil/trace.go:171","msg":"trace[1599288073] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"873.315215ms","start":"2026-02-07T01:50:00.009354Z","end":"2026-02-07T01:50:00.882669Z","steps":["trace[1599288073] 'process raft request' (duration: 872.277381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:50:00.882702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:50:00.009344Z","time spent":"873.343597ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:51:05.465522Z","caller":"traceutil/trace.go:171","msg":"trace[1160956157] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"182.958018ms","start":"2026-02-07T01:51:05.282557Z","end":"2026-02-07T01:51:05.465515Z","steps":["trace[1160956157] 'process raft request' (duration: 182.752366ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:05.465402Z","caller":"traceutil/trace.go:171","msg":"trace[780287656] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:896; }","duration":"171.881527ms","start":"2026-02-07T01:51:05.293506Z","end":"2026-02-07T01:51:05.465388Z","steps":["trace[780287656] 'read index received' (duration: 171.755476ms)","trace[780287656] 'applied index is now lower than readState.Index' (duration: 125.271µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:51:05.4658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.19802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T01:51:05.465817Z","caller":"traceutil/trace.go:171","msg":"trace[1296701558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:860; }","duration":"162.23785ms","start":"2026-02-07T01:51:05.303573Z","end":"2026-02-07T01:51:05.465811Z","steps":["trace[1296701558] 'agreement among raft nodes before linearized reading' (duration: 162.17077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:05.465897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.288851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-07T01:51:05.465909Z","caller":"traceutil/trace.go:171","msg":"trace[806483204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:860; }","duration":"162.307471ms","start":"2026-02-07T01:51:05.303598Z","end":"2026-02-07T01:51:05.465905Z","steps":["trace[806483204] 'agreement among raft nodes before linearized reading' (duration: 162.273901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:05.466053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.544073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-07T01:51:05.466066Z","caller":"traceutil/trace.go:171","msg":"trace[1564616864] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:860; }","duration":"172.581044ms","start":"2026-02-07T01:51:05.293481Z","end":"2026-02-07T01:51:05.466062Z","steps":["trace[1564616864] 'agreement among raft nodes before linearized reading' (duration: 172.22952ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:05.602211Z","caller":"traceutil/trace.go:171","msg":"trace[61644522] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"130.679184ms","start":"2026-02-07T01:51:05.471516Z","end":"2026-02-07T01:51:05.602195Z","steps":["trace[61644522] 'process raft request' (duration: 130.648724ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:05.602409Z","caller":"traceutil/trace.go:171","msg":"trace[1715467545] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"131.839285ms","start":"2026-02-07T01:51:05.47055Z","end":"2026-02-07T01:51:05.602389Z","steps":["trace[1715467545] 'process raft request' (duration: 97.048139ms)","trace[1715467545] 'compare' (duration: 34.480083ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:51:05.968879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.121072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:51:05.969387Z","caller":"traceutil/trace.go:171","msg":"trace[876983063] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"310.208591ms","start":"2026-02-07T01:51:05.659158Z","end":"2026-02-07T01:51:05.969366Z","steps":["trace[876983063] 'process raft request' (duration: 86.525564ms)","trace[876983063] 'compare' (duration: 222.96177ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:51:05.969505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:05.659143Z","time spent":"310.326602ms","remote":"127.0.0.1:36168","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-07T01:51:06.461491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.395408ms","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-07T01:51:06.461603Z","caller":"traceutil/trace.go:171","msg":"trace[351110400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:864; }","duration":"122.500049ms","start":"2026-02-07T01:51:06.339041Z","end":"2026-02-07T01:51:06.461541Z","steps":["trace[351110400] 'range keys from in-memory index tree' (duration: 122.240286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:06.461854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.228917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-07T01:51:06.461946Z","caller":"traceutil/trace.go:171","msg":"trace[2099830829] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:864; }","duration":"111.324618ms","start":"2026-02-07T01:51:06.350594Z","end":"2026-02-07T01:51:06.461918Z","steps":["trace[2099830829] 'range keys from in-memory index tree' (duration: 111.060726ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:07.730973Z","caller":"traceutil/trace.go:171","msg":"trace[2007701165] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"260.470377ms","start":"2026-02-07T01:51:07.47048Z","end":"2026-02-07T01:51:07.73095Z","steps":["trace[2007701165] 'process raft request' (duration: 260.349615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:07.731172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.240616ms","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-07T01:51:07.731198Z","caller":"traceutil/trace.go:171","msg":"trace[966710442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:866; }","duration":"231.312566ms","start":"2026-02-07T01:51:07.499879Z","end":"2026-02-07T01:51:07.731192Z","steps":["trace[966710442] 'agreement among raft nodes before linearized reading' (duration: 231.141504ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:07.730981Z","caller":"traceutil/trace.go:171","msg":"trace[899293440] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:903; }","duration":"231.042984ms","start":"2026-02-07T01:51:07.499918Z","end":"2026-02-07T01:51:07.730961Z","steps":["trace[899293440] 'read index received' (duration: 231.035764ms)","trace[899293440] 'applied index is now lower than readState.Index' (duration: 5.77µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:51:08.015529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.076606ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-07T01:51:08.015927Z","caller":"traceutil/trace.go:171","msg":"trace[446427698] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:903; }","duration":"284.631163ms","start":"2026-02-07T01:51:07.731279Z","end":"2026-02-07T01:51:08.01591Z","steps":["trace[446427698] 'read index received' (duration: 108.172443ms)","trace[446427698] 'applied index is now lower than readState.Index' (duration: 176.45773ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:51:08.01623Z","caller":"traceutil/trace.go:171","msg":"trace[1160650306] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"282.154487ms","start":"2026-02-07T01:51:07.734061Z","end":"2026-02-07T01:51:08.016216Z","steps":["trace[1160650306] 'process raft request' (duration: 281.770152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:08.016386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.4664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:51:08.017192Z","caller":"traceutil/trace.go:171","msg":"trace[1142302814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:867; }","duration":"387.293189ms","start":"2026-02-07T01:51:07.629885Z","end":"2026-02-07T01:51:08.017178Z","steps":["trace[1142302814] 'agreement among raft nodes before linearized reading' (duration: 386.469519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:08.017313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:07.629874Z","time spent":"387.42503ms","remote":"127.0.0.1:35870","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-07T01:51:08.016515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.276903ms","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-07T01:51:08.017734Z","caller":"traceutil/trace.go:171","msg":"trace[1528805178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:867; }","duration":"411.488387ms","start":"2026-02-07T01:51:07.606231Z","end":"2026-02-07T01:51:08.01772Z","steps":["trace[1528805178] 'agreement among raft nodes before linearized reading' (duration: 410.178302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:08.016587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.93318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T01:51:08.017847Z","caller":"traceutil/trace.go:171","msg":"trace[1573446855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:867; }","duration":"412.218075ms","start":"2026-02-07T01:51:07.605623Z","end":"2026-02-07T01:51:08.017841Z","steps":["trace[1573446855] 'agreement among raft nodes before linearized reading' (duration: 410.91899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:08.017868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:07.605608Z","time spent":"412.254476ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T01:51:08.019073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:07.606215Z","time spent":"412.841632ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-07T01:51:08.149106Z","caller":"traceutil/trace.go:171","msg":"trace[2118269552] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"124.10439ms","start":"2026-02-07T01:51:08.024982Z","end":"2026-02-07T01:51:08.149086Z","steps":["trace[2118269552] 'process raft request' (duration: 124.062669ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:08.149981Z","caller":"traceutil/trace.go:171","msg":"trace[1687326984] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"128.867504ms","start":"2026-02-07T01:51:08.021099Z","end":"2026-02-07T01:51:08.149966Z","steps":["trace[1687326984] 'process raft request' (duration: 67.224468ms)","trace[1687326984] 'compare' (duration: 60.504923ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:51:09.895635Z","caller":"traceutil/trace.go:171","msg":"trace[585262906] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"159.112513ms","start":"2026-02-07T01:51:09.736508Z","end":"2026-02-07T01:51:09.89562Z","steps":["trace[585262906] 'process raft request' (duration: 159.018672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:10.431175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.266956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:51:10.431326Z","caller":"traceutil/trace.go:171","msg":"trace[2026829131] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:910; }","duration":"154.782637ms","start":"2026-02-07T01:51:10.276527Z","end":"2026-02-07T01:51:10.431309Z","steps":["trace[2026829131] 'read index received' (duration: 21.571µs)","trace[2026829131] 'applied index is now lower than readState.Index' (duration: 154.760206ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:51:10.431406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.881059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:51:10.431438Z","caller":"traceutil/trace.go:171","msg":"trace[1078575808] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:0; response_revision:874; }","duration":"154.966299ms","start":"2026-02-07T01:51:10.276463Z","end":"2026-02-07T01:51:10.431429Z","steps":["trace[1078575808] 'agreement among raft nodes before linearized reading' (duration: 154.899248ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:10.431744Z","caller":"traceutil/trace.go:171","msg":"trace[2143516046] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"272.579065ms","start":"2026-02-07T01:51:10.159141Z","end":"2026-02-07T01:51:10.43172Z","steps":["trace[2143516046] 'process raft request' (duration: 272.122629ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:10.431921Z","caller":"traceutil/trace.go:171","msg":"trace[620652971] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"274.455689ms","start":"2026-02-07T01:51:10.157449Z","end":"2026-02-07T01:51:10.431904Z","steps":["trace[620652971] 'process raft request' (duration: 16.360152ms)","trace[620652971] 'compare' (duration: 257.102044ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:51:11.010197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.707172ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-07T01:51:11.010277Z","caller":"traceutil/trace.go:171","msg":"trace[1624076784] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"434.674641ms","start":"2026-02-07T01:51:10.575588Z","end":"2026-02-07T01:51:11.010262Z","steps":["trace[1624076784] 'read index received' (duration: 20.1µs)","trace[1624076784] 'applied index is now lower than readState.Index' (duration: 434.653161ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:51:11.010349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:10.511534Z","time spent":"498.812743ms","remote":"127.0.0.1:35958","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-07T01:51:11.010608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.009056ms","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-07T01:51:11.010637Z","caller":"traceutil/trace.go:171","msg":"trace[434882151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:875; }","duration":"435.066917ms","start":"2026-02-07T01:51:10.575561Z","end":"2026-02-07T01:51:11.010627Z","steps":["trace[434882151] 'agreement among raft nodes before linearized reading' (duration: 434.947635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:11.010664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:10.575549Z","time spent":"435.108597ms","remote":"127.0.0.1:36168","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-07T01:51:11.010813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.467612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:51:11.010839Z","caller":"traceutil/trace.go:171","msg":"trace[1606769343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:875; }","duration":"378.516764ms","start":"2026-02-07T01:51:10.632314Z","end":"2026-02-07T01:51:11.010831Z","steps":["trace[1606769343] 'agreement among raft nodes before linearized reading' (duration: 378.479003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:11.010864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:10.632302Z","time spent":"378.555424ms","remote":"127.0.0.1:35862","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-07T01:51:11.34537Z","caller":"traceutil/trace.go:171","msg":"trace[1918984136] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"245.847711ms","start":"2026-02-07T01:51:11.099503Z","end":"2026-02-07T01:51:11.345351Z","steps":["trace[1918984136] 'read index received' (duration: 225.143517ms)","trace[1918984136] 'applied index is now lower than readState.Index' (duration: 20.703364ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:51:11.346155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.633342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:51:11.346359Z","caller":"traceutil/trace.go:171","msg":"trace[1854166904] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:0; response_revision:877; }","duration":"246.860805ms","start":"2026-02-07T01:51:11.099485Z","end":"2026-02-07T01:51:11.346346Z","steps":["trace[1854166904] 'agreement among raft nodes before linearized reading' (duration: 246.575311ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:11.346968Z","caller":"traceutil/trace.go:171","msg":"trace[1545210378] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"332.031383ms","start":"2026-02-07T01:51:11.014922Z","end":"2026-02-07T01:51:11.346953Z","steps":["trace[1545210378] 'process raft request' (duration: 309.765308ms)","trace[1545210378] 'compare' (duration: 20.332069ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:51:11.34774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:11.01491Z","time spent":"332.773763ms","remote":"127.0.0.1:36168","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-07T01:51:11.592197Z","caller":"traceutil/trace.go:171","msg":"trace[182872212] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"141.986267ms","start":"2026-02-07T01:51:11.450189Z","end":"2026-02-07T01:51:11.592175Z","steps":["trace[182872212] 'process raft request' (duration: 141.863566ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:11.776938Z","caller":"traceutil/trace.go:171","msg":"trace[2041855099] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"108.027391ms","start":"2026-02-07T01:51:11.668871Z","end":"2026-02-07T01:51:11.776899Z","steps":["trace[2041855099] 'process raft request' (duration: 47.883047ms)","trace[2041855099] 'compare' (duration: 59.912441ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:51:12.352571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.442202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-07T01:51:12.352792Z","caller":"traceutil/trace.go:171","msg":"trace[1081113487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"208.669205ms","start":"2026-02-07T01:51:12.144104Z","end":"2026-02-07T01:51:12.352773Z","steps":["trace[1081113487] 'range keys from in-memory index tree' (duration: 207.899274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:12.354182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.523368ms","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-07T01:51:12.355261Z","caller":"traceutil/trace.go:171","msg":"trace[1429852467] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:886; }","duration":"136.626723ms","start":"2026-02-07T01:51:12.218619Z","end":"2026-02-07T01:51:12.355245Z","steps":["trace[1429852467] 'range keys from in-memory index tree' (duration: 134.605304ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:12.460213Z","caller":"traceutil/trace.go:171","msg":"trace[937914182] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"104.216856ms","start":"2026-02-07T01:51:12.35598Z","end":"2026-02-07T01:51:12.460197Z","steps":["trace[937914182] 'process raft request' (duration: 104.117474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:12.827546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.3107ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-07T01:51:12.82774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:12.356414Z","time spent":"471.32043ms","remote":"127.0.0.1:35908","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-07T01:51:12.827807Z","caller":"traceutil/trace.go:171","msg":"trace[1876258464] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"201.492281ms","start":"2026-02-07T01:51:12.626298Z","end":"2026-02-07T01:51:12.82779Z","steps":["trace[1876258464] 'process raft request' (duration: 201.449391ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:12.828259Z","caller":"traceutil/trace.go:171","msg":"trace[688966001] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:926; }","duration":"389.0352ms","start":"2026-02-07T01:51:12.439205Z","end":"2026-02-07T01:51:12.828241Z","steps":["trace[688966001] 'read index received' (duration: 21.442319ms)","trace[688966001] 'applied index is now lower than readState.Index' (duration: 367.590981ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:51:12.828606Z","caller":"traceutil/trace.go:171","msg":"trace[9870849] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"470.325295ms","start":"2026-02-07T01:51:12.358265Z","end":"2026-02-07T01:51:12.82859Z","steps":["trace[9870849] 'process raft request' (duration: 469.363391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:12.828717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:12.358256Z","time spent":"470.396326ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:51:12.829303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.082646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-07T01:51:12.829346Z","caller":"traceutil/trace.go:171","msg":"trace[788942587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:889; }","duration":"390.172407ms","start":"2026-02-07T01:51:12.439162Z","end":"2026-02-07T01:51:12.829335Z","steps":["trace[788942587] 'agreement among raft nodes before linearized reading' (duration: 390.015904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:12.829375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:12.439144Z","time spent":"390.224387ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T01:51:12.830593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.440602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-07T01:51:12.830662Z","caller":"traceutil/trace.go:171","msg":"trace[1488075444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:889; }","duration":"388.527513ms","start":"2026-02-07T01:51:12.442122Z","end":"2026-02-07T01:51:12.830649Z","steps":["trace[1488075444] 'agreement among raft nodes before linearized reading' (duration: 388.36514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:12.830702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:12.442116Z","time spent":"388.577733ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T01:51:12.831274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.566318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:51:12.831318Z","caller":"traceutil/trace.go:171","msg":"trace[803996456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"200.633648ms","start":"2026-02-07T01:51:12.630672Z","end":"2026-02-07T01:51:12.831306Z","steps":["trace[803996456] 'agreement among raft nodes before linearized reading' (duration: 200.571787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:12.831876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.516932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:51:12.831923Z","caller":"traceutil/trace.go:171","msg":"trace[228103712] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:889; }","duration":"253.591524ms","start":"2026-02-07T01:51:12.578319Z","end":"2026-02-07T01:51:12.83191Z","steps":["trace[228103712] 'agreement among raft nodes before linearized reading' (duration: 253.518863ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:12.954361Z","caller":"traceutil/trace.go:171","msg":"trace[999177281] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:930; }","duration":"111.753015ms","start":"2026-02-07T01:51:12.84259Z","end":"2026-02-07T01:51:12.954343Z","steps":["trace[999177281] 'read index received' (duration: 14.256886ms)","trace[999177281] 'applied index is now lower than readState.Index' (duration: 97.495519ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:51:12.954987Z","caller":"traceutil/trace.go:171","msg":"trace[1678783595] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"115.198475ms","start":"2026-02-07T01:51:12.839758Z","end":"2026-02-07T01:51:12.954957Z","steps":["trace[1678783595] 'process raft request' (duration: 114.551845ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:12.955371Z","caller":"traceutil/trace.go:171","msg":"trace[427620107] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"118.074936ms","start":"2026-02-07T01:51:12.837289Z","end":"2026-02-07T01:51:12.955364Z","steps":["trace[427620107] 'process raft request' (duration: 115.997936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:12.955584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.956802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f39a56be-2672-45e9-8ff7-95e121d04aa4\" ","response":"range_response_count:1 size:3534"} {"level":"info","ts":"2026-02-07T01:51:12.955632Z","caller":"traceutil/trace.go:171","msg":"trace[960389703] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f39a56be-2672-45e9-8ff7-95e121d04aa4; range_end:; response_count:1; response_revision:893; }","duration":"113.051703ms","start":"2026-02-07T01:51:12.842572Z","end":"2026-02-07T01:51:12.955624Z","steps":["trace[960389703] 'agreement among raft nodes before linearized reading' (duration: 112.885661ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:12.95585Z","caller":"traceutil/trace.go:171","msg":"trace[477428762] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"117.576238ms","start":"2026-02-07T01:51:12.838266Z","end":"2026-02-07T01:51:12.955843Z","steps":["trace[477428762] 'process raft request' (duration: 116.004086ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:15.195633Z","caller":"traceutil/trace.go:171","msg":"trace[1457692936] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:938; }","duration":"181.696823ms","start":"2026-02-07T01:51:15.01392Z","end":"2026-02-07T01:51:15.195617Z","steps":["trace[1457692936] 'read index received' (duration: 144.15734ms)","trace[1457692936] 'applied index is now lower than readState.Index' (duration: 37.538743ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:51:15.195945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.010498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f39a56be-2672-45e9-8ff7-95e121d04aa4\" ","response":"range_response_count:1 size:3534"} {"level":"info","ts":"2026-02-07T01:51:15.19602Z","caller":"traceutil/trace.go:171","msg":"trace[173719911] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f39a56be-2672-45e9-8ff7-95e121d04aa4; range_end:; response_count:1; response_revision:900; }","duration":"182.14747ms","start":"2026-02-07T01:51:15.013865Z","end":"2026-02-07T01:51:15.196012Z","steps":["trace[173719911] 'agreement among raft nodes before linearized reading' (duration: 181.972147ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:15.196246Z","caller":"traceutil/trace.go:171","msg":"trace[1265852774] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"230.617876ms","start":"2026-02-07T01:51:14.965604Z","end":"2026-02-07T01:51:15.196222Z","steps":["trace[1265852774] 'process raft request' (duration: 229.961615ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:15.196252Z","caller":"traceutil/trace.go:171","msg":"trace[1948598657] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"230.732598ms","start":"2026-02-07T01:51:14.965494Z","end":"2026-02-07T01:51:15.196226Z","steps":["trace[1948598657] 'process raft request' (duration: 192.626826ms)","trace[1948598657] 'compare' (duration: 37.011544ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:51:15.577433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.677883ms","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-07T01:51:15.577586Z","caller":"traceutil/trace.go:171","msg":"trace[744517935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"213.881636ms","start":"2026-02-07T01:51:15.363684Z","end":"2026-02-07T01:51:15.577565Z","steps":["trace[744517935] 'range keys from in-memory index tree' (duration: 213.433529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:16.373835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.373607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f39a56be-2672-45e9-8ff7-95e121d04aa4\" ","response":"range_response_count:1 size:3534"} {"level":"info","ts":"2026-02-07T01:51:16.373915Z","caller":"traceutil/trace.go:171","msg":"trace[1452712424] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f39a56be-2672-45e9-8ff7-95e121d04aa4; range_end:; response_count:1; response_revision:903; }","duration":"172.493949ms","start":"2026-02-07T01:51:16.2014Z","end":"2026-02-07T01:51:16.373894Z","steps":["trace[1452712424] 'range keys from in-memory index tree' (duration: 172.227714ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:26.508747Z","caller":"traceutil/trace.go:171","msg":"trace[1127320447] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"275.205112ms","start":"2026-02-07T01:51:26.233523Z","end":"2026-02-07T01:51:26.508728Z","steps":["trace[1127320447] 'process raft request' (duration: 273.626725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:32.656051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.833199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-07T01:51:32.656108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.761067ms","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-07T01:51:32.656118Z","caller":"traceutil/trace.go:171","msg":"trace[146662592] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:971; }","duration":"435.935231ms","start":"2026-02-07T01:51:32.220165Z","end":"2026-02-07T01:51:32.656101Z","steps":["trace[146662592] 'range keys from in-memory index tree' (duration: 435.701985ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:51:32.656131Z","caller":"traceutil/trace.go:171","msg":"trace[1053914196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:971; }","duration":"435.793088ms","start":"2026-02-07T01:51:32.22033Z","end":"2026-02-07T01:51:32.656123Z","steps":["trace[1053914196] 'range keys from in-memory index tree' (duration: 435.717185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:32.656148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:32.220326Z","time spent":"435.817988ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T01:51:32.656183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:32.220146Z","time spent":"436.028624ms","remote":"127.0.0.1:35908","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-07T01:51:32.871101Z","caller":"traceutil/trace.go:171","msg":"trace[639573489] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"192.440443ms","start":"2026-02-07T01:51:32.678641Z","end":"2026-02-07T01:51:32.871082Z","steps":["trace[639573489] 'process raft request' (duration: 102.124061ms)","trace[639573489] 'compare' (duration: 90.24603ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:51:33.546584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.61135ms","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-07T01:51:33.546648Z","caller":"traceutil/trace.go:171","msg":"trace[1412919446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"542.707193ms","start":"2026-02-07T01:51:33.003926Z","end":"2026-02-07T01:51:33.546633Z","steps":["trace[1412919446] 'range keys from in-memory index tree' (duration: 542.494497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:33.546682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:51:33.003913Z","time spent":"542.760254ms","remote":"127.0.0.1:36168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T01:51:33.547123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.078334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T01:51:33.547154Z","caller":"traceutil/trace.go:171","msg":"trace[1999495224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"259.144426ms","start":"2026-02-07T01:51:33.288001Z","end":"2026-02-07T01:51:33.547145Z","steps":["trace[1999495224] 'range keys from in-memory index tree' (duration: 258.982112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:51:33.547319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.731458ms","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-07T01:51:33.547345Z","caller":"traceutil/trace.go:171","msg":"trace[1027815025] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:973; }","duration":"257.805699ms","start":"2026-02-07T01:51:33.289531Z","end":"2026-02-07T01:51:33.547337Z","steps":["trace[1027815025] 'range keys from in-memory index tree' (duration: 257.599814ms)"],"step_count":1}