{"level":"warn","ts":"2026-02-05T22:11:52.047924Z","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-05T22:11:52.048182Z","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-05T22:11:52.048348Z","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-05T22:11:52.048418Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-05T22:11:52.04852Z","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-05T22:11:52.04922Z","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-05T22:11:52.050036Z","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-05T22:11:52.058202Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.849576ms"} {"level":"info","ts":"2026-02-05T22:11:52.081703Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-02-05T22:11:52.081914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T22:11:52.081978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-02-05T22:11:52.082032Z","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-05T22:11:52.082141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-02-05T22:11:52.082231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-02-05T22:11:52.105524Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T22:11:52.115768Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T22:11:52.129753Z","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-05T22:11:52.13516Z","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-05T22:11:52.135576Z","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-05T22:11:52.135872Z","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-05T22:11:52.138526Z","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-05T22:11:52.138545Z","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-05T22:11:52.138488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-02-05T22:11:52.138805Z","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-05T22:11:52.144912Z","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-05T22:11:52.145141Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-05T22:11:52.145168Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-05T22:11:52.145266Z","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-05T22:11:52.145317Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T22:11:52.283665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T22:11:52.283871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T22:11:52.283959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-02-05T22:11:52.284005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-02-05T22:11:52.284058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-05T22:11:52.284104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-02-05T22:11:52.284156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-05T22:11:52.290267Z","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-05T22:11:52.290337Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T22:11:52.290765Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:11:52.290906Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T22:11:52.29454Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-02-05T22:11:52.296237Z","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-05T22:11:52.297152Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T22:11:52.297166Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T22:11:52.297393Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:11:52.297414Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:11:52.316505Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-05T22:11:56.028908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.732351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:11:56.029058Z","caller":"traceutil/trace.go:171","msg":"trace[1859632519] linearizableReadLoop","detail":"{readStateIndex:152; appliedIndex:151; }","duration":"334.041808ms","start":"2026-02-05T22:11:55.694984Z","end":"2026-02-05T22:11:56.029025Z","steps":["trace[1859632519] 'read index received' (duration: 20.526672ms)","trace[1859632519] 'applied index is now lower than readState.Index' (duration: 313.513916ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:11:56.029227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.245012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:11:56.029269Z","caller":"traceutil/trace.go:171","msg":"trace[1959714401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:147; }","duration":"334.302733ms","start":"2026-02-05T22:11:55.694938Z","end":"2026-02-05T22:11:56.02924Z","steps":["trace[1959714401] 'agreement among raft nodes before linearized reading' (duration: 334.202501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:11:56.029299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:11:55.694917Z","time spent":"334.372714ms","remote":"127.0.0.1:33122","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-05T22:11:56.029324Z","caller":"traceutil/trace.go:171","msg":"trace[1399172045] transaction","detail":"{read_only:false; response_revision:147; number_of_response:1; }","duration":"371.058498ms","start":"2026-02-05T22:11:55.658218Z","end":"2026-02-05T22:11:56.029277Z","steps":["trace[1399172045] 'process raft request' (duration: 57.355189ms)","trace[1399172045] 'compare' (duration: 312.594328ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:11:56.02953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:11:55.65821Z","time spent":"371.251701ms","remote":"127.0.0.1:33446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":699,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T22:12:02.042342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.339799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-05T22:12:02.04247Z","caller":"traceutil/trace.go:171","msg":"trace[1756567755] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:328; }","duration":"900.508962ms","start":"2026-02-05T22:12:01.141937Z","end":"2026-02-05T22:12:02.042446Z","steps":["trace[1756567755] 'range keys from in-memory index tree' (duration: 900.238197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:02.042522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:01.14188Z","time spent":"900.632114ms","remote":"127.0.0.1:33312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-02-05T22:12:02.042467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.255071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-05T22:12:02.042613Z","caller":"traceutil/trace.go:171","msg":"trace[1214285650] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:328; }","duration":"951.482935ms","start":"2026-02-05T22:12:01.091105Z","end":"2026-02-05T22:12:02.042588Z","steps":["trace[1214285650] 'range keys from in-memory index tree' (duration: 951.18038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:02.042662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:01.091089Z","time spent":"951.560896ms","remote":"127.0.0.1:33218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-05T22:12:13.091011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.530613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:12:13.091095Z","caller":"traceutil/trace.go:171","msg":"trace[47382203] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"116.17135ms","start":"2026-02-05T22:12:12.974913Z","end":"2026-02-05T22:12:13.091084Z","steps":["trace[47382203] 'read index received' (duration: 34.961µs)","trace[47382203] 'applied index is now lower than readState.Index' (duration: 116.135599ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:12:13.091253Z","caller":"traceutil/trace.go:171","msg":"trace[1210767137] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"253.449409ms","start":"2026-02-05T22:12:12.837793Z","end":"2026-02-05T22:12:13.091243Z","steps":["trace[1210767137] 'process raft request' (duration: 88.528488ms)","trace[1210767137] 'compare' (duration: 164.444342ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:12:13.09138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.466286ms","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-05T22:12:13.091402Z","caller":"traceutil/trace.go:171","msg":"trace[1528481065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:459; }","duration":"116.510897ms","start":"2026-02-05T22:12:12.974881Z","end":"2026-02-05T22:12:13.091392Z","steps":["trace[1528481065] 'agreement among raft nodes before linearized reading' (duration: 116.472656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:23.655967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360082920511069,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T22:12:23.71854Z","caller":"traceutil/trace.go:171","msg":"trace[403951881] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"563.349994ms","start":"2026-02-05T22:12:23.155175Z","end":"2026-02-05T22:12:23.718525Z","steps":["trace[403951881] 'read index received' (duration: 563.228212ms)","trace[403951881] 'applied index is now lower than readState.Index' (duration: 121.082µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:12:23.718622Z","caller":"traceutil/trace.go:171","msg":"trace[307104043] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"578.188878ms","start":"2026-02-05T22:12:23.140426Z","end":"2026-02-05T22:12:23.718615Z","steps":["trace[307104043] 'process raft request' (duration: 577.987804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:23.718689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:23.140417Z","time spent":"578.219308ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:12:23.806525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.416024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:12:23.806583Z","caller":"traceutil/trace.go:171","msg":"trace[1952797341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"470.510666ms","start":"2026-02-05T22:12:23.336059Z","end":"2026-02-05T22:12:23.806569Z","steps":["trace[1952797341] 'agreement among raft nodes before linearized reading' (duration: 382.832353ms)","trace[1952797341] 'range keys from in-memory index tree' (duration: 87.589631ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:12:23.806608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:23.336037Z","time spent":"470.566906ms","remote":"127.0.0.1:33122","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-05T22:12:23.806789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.609957ms","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-05T22:12:23.806803Z","caller":"traceutil/trace.go:171","msg":"trace[1396463879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"651.634338ms","start":"2026-02-05T22:12:23.155163Z","end":"2026-02-05T22:12:23.806798Z","steps":["trace[1396463879] 'agreement among raft nodes before linearized reading' (duration: 563.68951ms)","trace[1396463879] 'range keys from in-memory index tree' (duration: 87.906867ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:12:23.806819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:23.155157Z","time spent":"651.657778ms","remote":"127.0.0.1:33364","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-05T22:12:23.806833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.605237ms","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-05T22:12:23.806908Z","caller":"traceutil/trace.go:171","msg":"trace[930644047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"651.686448ms","start":"2026-02-05T22:12:23.155195Z","end":"2026-02-05T22:12:23.806881Z","steps":["trace[930644047] 'agreement among raft nodes before linearized reading' (duration: 563.673559ms)","trace[930644047] 'range keys from in-memory index tree' (duration: 87.908327ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:12:23.806949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:23.155192Z","time spent":"651.748119ms","remote":"127.0.0.1:33364","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":"info","ts":"2026-02-05T22:12:29.183175Z","caller":"traceutil/trace.go:171","msg":"trace[374151592] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"167.354817ms","start":"2026-02-05T22:12:29.015803Z","end":"2026-02-05T22:12:29.183157Z","steps":["trace[374151592] 'process raft request' (duration: 167.156244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:30.247132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.762112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:12:30.247222Z","caller":"traceutil/trace.go:171","msg":"trace[800965291] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"507.213766ms","start":"2026-02-05T22:12:29.739993Z","end":"2026-02-05T22:12:30.247207Z","steps":["trace[800965291] 'read index received' (duration: 264.23626ms)","trace[800965291] 'applied index is now lower than readState.Index' (duration: 242.976466ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:12:30.247528Z","caller":"traceutil/trace.go:171","msg":"trace[1155932107] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.143224893s","start":"2026-02-05T22:12:29.104289Z","end":"2026-02-05T22:12:30.247514Z","steps":["trace[1155932107] 'process raft request' (duration: 900.002623ms)","trace[1155932107] 'compare' (duration: 242.560358ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:12:30.247593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:29.104271Z","time spent":"1.143288334s","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:12:30.247765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.808646ms","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-05T22:12:30.247815Z","caller":"traceutil/trace.go:171","msg":"trace[841846374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"507.883627ms","start":"2026-02-05T22:12:29.739901Z","end":"2026-02-05T22:12:30.247785Z","steps":["trace[841846374] 'agreement among raft nodes before linearized reading' (duration: 507.832716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:30.247845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:29.739866Z","time spent":"507.972749ms","remote":"127.0.0.1:33364","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-05T22:12:30.247975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.957918ms","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-05T22:12:30.248001Z","caller":"traceutil/trace.go:171","msg":"trace[1792363520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"395.003499ms","start":"2026-02-05T22:12:29.852989Z","end":"2026-02-05T22:12:30.247993Z","steps":["trace[1792363520] 'agreement among raft nodes before linearized reading' (duration: 394.960968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:30.248027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:29.852978Z","time spent":"395.041269ms","remote":"127.0.0.1:33364","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-05T22:12:30.248611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.96644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T22:12:30.248652Z","caller":"traceutil/trace.go:171","msg":"trace[1315709092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"354.030271ms","start":"2026-02-05T22:12:29.894612Z","end":"2026-02-05T22:12:30.248642Z","steps":["trace[1315709092] 'agreement among raft nodes before linearized reading' (duration: 353.958779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:30.248681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:29.894584Z","time spent":"354.090112ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T22:12:33.254986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.551544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-05T22:12:33.255061Z","caller":"traceutil/trace.go:171","msg":"trace[808349021] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:553; }","duration":"352.667886ms","start":"2026-02-05T22:12:32.902375Z","end":"2026-02-05T22:12:33.255043Z","steps":["trace[808349021] 'range keys from in-memory index tree' (duration: 352.405211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:33.255095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:32.902355Z","time spent":"352.731277ms","remote":"127.0.0.1:33218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-05T22:12:46.859289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.853834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:12:46.85938Z","caller":"traceutil/trace.go:171","msg":"trace[841017868] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"430.80319ms","start":"2026-02-05T22:12:46.428562Z","end":"2026-02-05T22:12:46.859365Z","steps":["trace[841017868] 'read index received' (duration: 14.475387ms)","trace[841017868] 'applied index is now lower than readState.Index' (duration: 416.326913ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:12:46.859593Z","caller":"traceutil/trace.go:171","msg":"trace[546659711] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"434.993857ms","start":"2026-02-05T22:12:46.424568Z","end":"2026-02-05T22:12:46.859562Z","steps":["trace[546659711] 'process raft request' (duration: 265.775757ms)","trace[546659711] 'compare' (duration: 168.521428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:12:46.859661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:46.424563Z","time spent":"435.059099ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:12:46.859926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.356831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T22:12:46.859956Z","caller":"traceutil/trace.go:171","msg":"trace[769445530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"431.394352ms","start":"2026-02-05T22:12:46.428552Z","end":"2026-02-05T22:12:46.859947Z","steps":["trace[769445530] 'agreement among raft nodes before linearized reading' (duration: 431.28733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:46.859985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:46.428548Z","time spent":"431.430283ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T22:12:46.860172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.166218ms","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-05T22:12:46.8602Z","caller":"traceutil/trace.go:171","msg":"trace[722698256] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:714; }","duration":"257.22567ms","start":"2026-02-05T22:12:46.602966Z","end":"2026-02-05T22:12:46.860191Z","steps":["trace[722698256] 'agreement among raft nodes before linearized reading' (duration: 257.137638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:47.829417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360082920511453,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T22:12:48.015304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.131337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:12:48.016007Z","caller":"traceutil/trace.go:171","msg":"trace[499138484] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:739; }","duration":"686.763757ms","start":"2026-02-05T22:12:47.329227Z","end":"2026-02-05T22:12:48.015991Z","steps":["trace[499138484] 'read index received' (duration: 630.491916ms)","trace[499138484] 'applied index is now lower than readState.Index' (duration: 56.271031ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:12:48.016373Z","caller":"traceutil/trace.go:171","msg":"trace[1313465998] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"1.150400336s","start":"2026-02-05T22:12:46.865957Z","end":"2026-02-05T22:12:48.016357Z","steps":["trace[1313465998] 'process raft request' (duration: 217.149268ms)","trace[1313465998] 'compare' (duration: 930.900684ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:12:48.016423Z","caller":"traceutil/trace.go:171","msg":"trace[949159172] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"1.119773779s","start":"2026-02-05T22:12:46.896641Z","end":"2026-02-05T22:12:48.016415Z","steps":["trace[949159172] 'process raft request' (duration: 1.11928994s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:48.018564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:46.865939Z","time spent":"1.152567075s","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:12:48.018705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:46.896622Z","time spent":"1.121981409s","remote":"127.0.0.1:33140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:12:48.016596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.368888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T22:12:48.019006Z","caller":"traceutil/trace.go:171","msg":"trace[1088751384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:716; }","duration":"689.810734ms","start":"2026-02-05T22:12:47.329178Z","end":"2026-02-05T22:12:48.018989Z","steps":["trace[1088751384] 'agreement among raft nodes before linearized reading' (duration: 687.272227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:48.019055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:47.329159Z","time spent":"689.884605ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T22:12:48.018231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.606248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:12:48.019192Z","caller":"traceutil/trace.go:171","msg":"trace[365968798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"678.618006ms","start":"2026-02-05T22:12:47.340565Z","end":"2026-02-05T22:12:48.019183Z","steps":["trace[365968798] 'agreement among raft nodes before linearized reading' (duration: 677.572037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:48.019225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:47.340551Z","time spent":"678.666508ms","remote":"127.0.0.1:33136","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-05T22:12:48.01845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.336884ms","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-05T22:12:48.019363Z","caller":"traceutil/trace.go:171","msg":"trace[1468803050] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:716; }","duration":"630.245281ms","start":"2026-02-05T22:12:47.389099Z","end":"2026-02-05T22:12:48.019344Z","steps":["trace[1468803050] 'agreement among raft nodes before linearized reading' (duration: 629.178401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:48.019433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:47.389066Z","time spent":"630.352713ms","remote":"127.0.0.1:33364","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-05T22:12:48.277253Z","caller":"traceutil/trace.go:171","msg":"trace[1652529366] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"251.147517ms","start":"2026-02-05T22:12:48.026072Z","end":"2026-02-05T22:12:48.277219Z","steps":["trace[1652529366] 'process raft request' (duration: 251.041545ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:12:48.282659Z","caller":"traceutil/trace.go:171","msg":"trace[492896239] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"254.719513ms","start":"2026-02-05T22:12:48.027932Z","end":"2026-02-05T22:12:48.282651Z","steps":["trace[492896239] 'process raft request' (duration: 254.313056ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:12:48.283413Z","caller":"traceutil/trace.go:171","msg":"trace[547052369] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"201.637891ms","start":"2026-02-05T22:12:48.081764Z","end":"2026-02-05T22:12:48.283402Z","steps":["trace[547052369] 'process raft request' (duration: 200.620163ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:12:48.282619Z","caller":"traceutil/trace.go:171","msg":"trace[1467609175] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"254.633011ms","start":"2026-02-05T22:12:48.027971Z","end":"2026-02-05T22:12:48.282604Z","steps":["trace[1467609175] 'read index received' (duration: 249.635509ms)","trace[1467609175] 'applied index is now lower than readState.Index' (duration: 4.996782ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:12:48.284176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.18776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-05T22:12:48.284231Z","caller":"traceutil/trace.go:171","msg":"trace[570703706] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:719; }","duration":"256.261311ms","start":"2026-02-05T22:12:48.027962Z","end":"2026-02-05T22:12:48.284223Z","steps":["trace[570703706] 'agreement among raft nodes before linearized reading' (duration: 256.117809ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:12:50.135708Z","caller":"traceutil/trace.go:171","msg":"trace[641833739] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"103.840021ms","start":"2026-02-05T22:12:50.031844Z","end":"2026-02-05T22:12:50.135684Z","steps":["trace[641833739] 'process raft request' (duration: 103.722559ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:12:50.440338Z","caller":"traceutil/trace.go:171","msg":"trace[1553378137] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"154.616391ms","start":"2026-02-05T22:12:50.285704Z","end":"2026-02-05T22:12:50.44032Z","steps":["trace[1553378137] 'read index received' (duration: 154.485629ms)","trace[1553378137] 'applied index is now lower than readState.Index' (duration: 130.122µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:12:50.44044Z","caller":"traceutil/trace.go:171","msg":"trace[1266997726] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"155.326544ms","start":"2026-02-05T22:12:50.285106Z","end":"2026-02-05T22:12:50.440433Z","steps":["trace[1266997726] 'process raft request' (duration: 155.09388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:50.440547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.770424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:12:50.440593Z","caller":"traceutil/trace.go:171","msg":"trace[1479318562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"101.825335ms","start":"2026-02-05T22:12:50.338755Z","end":"2026-02-05T22:12:50.440581Z","steps":["trace[1479318562] 'agreement among raft nodes before linearized reading' (duration: 101.750193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:50.440756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.05366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T22:12:50.440774Z","caller":"traceutil/trace.go:171","msg":"trace[1111082643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:723; }","duration":"155.08287ms","start":"2026-02-05T22:12:50.285686Z","end":"2026-02-05T22:12:50.440768Z","steps":["trace[1111082643] 'agreement among raft nodes before linearized reading' (duration: 155.015789ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:12:50.597031Z","caller":"traceutil/trace.go:171","msg":"trace[2094005673] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:748; }","duration":"145.610404ms","start":"2026-02-05T22:12:50.451402Z","end":"2026-02-05T22:12:50.597013Z","steps":["trace[2094005673] 'read index received' (duration: 54.910096ms)","trace[2094005673] 'applied index is now lower than readState.Index' (duration: 90.699568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:12:50.597174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.754107ms","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-05T22:12:50.59719Z","caller":"traceutil/trace.go:171","msg":"trace[781706270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:724; }","duration":"145.798397ms","start":"2026-02-05T22:12:50.451387Z","end":"2026-02-05T22:12:50.597185Z","steps":["trace[781706270] 'agreement among raft nodes before linearized reading' (duration: 145.687845ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:12:50.597234Z","caller":"traceutil/trace.go:171","msg":"trace[986559282] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"152.044294ms","start":"2026-02-05T22:12:50.445165Z","end":"2026-02-05T22:12:50.59721Z","steps":["trace[986559282] 'process raft request' (duration: 61.203013ms)","trace[986559282] 'compare' (duration: 90.529215ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:12:51.186502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.617651ms","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-05T22:12:51.186561Z","caller":"traceutil/trace.go:171","msg":"trace[1129474721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:726; }","duration":"314.720303ms","start":"2026-02-05T22:12:50.871826Z","end":"2026-02-05T22:12:51.186547Z","steps":["trace[1129474721] 'range keys from in-memory index tree' (duration: 314.468808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:51.186589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:50.871807Z","time spent":"314.775644ms","remote":"127.0.0.1:33364","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":"info","ts":"2026-02-05T22:12:51.358768Z","caller":"traceutil/trace.go:171","msg":"trace[571943562] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"165.473932ms","start":"2026-02-05T22:12:51.193278Z","end":"2026-02-05T22:12:51.358752Z","steps":["trace[571943562] 'process raft request' (duration: 165.36891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:12:59.03022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.658115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T22:12:59.030273Z","caller":"traceutil/trace.go:171","msg":"trace[1320716265] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:753; }","duration":"265.721206ms","start":"2026-02-05T22:12:58.764539Z","end":"2026-02-05T22:12:59.03026Z","steps":["trace[1320716265] 'count revisions from in-memory index tree' (duration: 265.553354ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:00.193163Z","caller":"traceutil/trace.go:171","msg":"trace[1680226911] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"384.539215ms","start":"2026-02-05T22:12:59.808604Z","end":"2026-02-05T22:13:00.193143Z","steps":["trace[1680226911] 'process raft request' (duration: 383.805101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:00.193272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:12:59.808587Z","time spent":"384.623486ms","remote":"127.0.0.1:33290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4768,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:13:00.533284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.518372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:13:00.533401Z","caller":"traceutil/trace.go:171","msg":"trace[55931154] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"193.49095ms","start":"2026-02-05T22:13:00.339876Z","end":"2026-02-05T22:13:00.533366Z","steps":["trace[55931154] 'read index received' (duration: 36.23µs)","trace[55931154] 'applied index is now lower than readState.Index' (duration: 193.45367ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:00.533584Z","caller":"traceutil/trace.go:171","msg":"trace[1151825499] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"335.02425ms","start":"2026-02-05T22:13:00.198546Z","end":"2026-02-05T22:13:00.533571Z","steps":["trace[1151825499] 'process raft request' (duration: 136.14313ms)","trace[1151825499] 'compare' (duration: 198.35997ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:13:00.533693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:00.198523Z","time spent":"335.093991ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:13:00.533858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.9792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:13:00.533887Z","caller":"traceutil/trace.go:171","msg":"trace[89022643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"194.03215ms","start":"2026-02-05T22:13:00.339845Z","end":"2026-02-05T22:13:00.533877Z","steps":["trace[89022643] 'agreement among raft nodes before linearized reading' (duration: 193.98467ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:04.74495Z","caller":"traceutil/trace.go:171","msg":"trace[1233991639] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"105.423852ms","start":"2026-02-05T22:13:04.639513Z","end":"2026-02-05T22:13:04.744937Z","steps":["trace[1233991639] 'read index received' (duration: 105.325459ms)","trace[1233991639] 'applied index is now lower than readState.Index' (duration: 97.843µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:04.745023Z","caller":"traceutil/trace.go:171","msg":"trace[647670792] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"125.441471ms","start":"2026-02-05T22:13:04.619577Z","end":"2026-02-05T22:13:04.745018Z","steps":["trace[647670792] 'process raft request' (duration: 125.265677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:04.74515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.630885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T22:13:04.745167Z","caller":"traceutil/trace.go:171","msg":"trace[709547878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"105.670746ms","start":"2026-02-05T22:13:04.639491Z","end":"2026-02-05T22:13:04.745162Z","steps":["trace[709547878] 'agreement among raft nodes before linearized reading' (duration: 105.594624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:11.260068Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360082920511660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T22:13:11.760997Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360082920511660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T22:13:11.770656Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017462532s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T22:13:11.771079Z","caller":"traceutil/trace.go:171","msg":"trace[1946381585] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"1.017941481s","start":"2026-02-05T22:13:10.753117Z","end":"2026-02-05T22:13:11.771059Z","steps":["trace[1946381585] 'process raft request' (duration: 1.017800049s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:11.771194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:10.753103Z","time spent":"1.018016832s","remote":"127.0.0.1:33364","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-05T22:13:12.423177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.340591ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T22:13:12.423278Z","caller":"traceutil/trace.go:171","msg":"trace[836286755] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:814; }","duration":"1.664239448s","start":"2026-02-05T22:13:10.759019Z","end":"2026-02-05T22:13:12.423258Z","steps":["trace[836286755] 'read index received' (duration: 1.011830708s)","trace[836286755] 'applied index is now lower than readState.Index' (duration: 652.40717ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:13:12.423926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664889679s","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-05T22:13:12.423967Z","caller":"traceutil/trace.go:171","msg":"trace[326117234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:785; }","duration":"1.66496189s","start":"2026-02-05T22:13:10.758993Z","end":"2026-02-05T22:13:12.423955Z","steps":["trace[326117234] 'agreement among raft nodes before linearized reading' (duration: 1.664787767s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:12.424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:10.758984Z","time spent":"1.665007551s","remote":"127.0.0.1:33364","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-05T22:13:12.4244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.655127399s","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-05T22:13:12.424433Z","caller":"traceutil/trace.go:171","msg":"trace[1575107854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"1.655175309s","start":"2026-02-05T22:13:10.769248Z","end":"2026-02-05T22:13:12.424423Z","steps":["trace[1575107854] 'agreement among raft nodes before linearized reading' (duration: 1.655073327s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:12.424461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:10.769242Z","time spent":"1.655213s","remote":"127.0.0.1:33364","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-05T22:13:12.424923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.18164ms","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-05T22:13:12.4258Z","caller":"traceutil/trace.go:171","msg":"trace[780629372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"996.081167ms","start":"2026-02-05T22:13:11.429706Z","end":"2026-02-05T22:13:12.425787Z","steps":["trace[780629372] 'agreement among raft nodes before linearized reading' (duration: 995.13808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:12.425842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:11.429695Z","time spent":"996.133927ms","remote":"127.0.0.1:33364","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-05T22:13:12.425955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.630801728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T22:13:12.426429Z","caller":"traceutil/trace.go:171","msg":"trace[984269198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"1.631302117s","start":"2026-02-05T22:13:10.795091Z","end":"2026-02-05T22:13:12.426393Z","steps":["trace[984269198] 'agreement among raft nodes before linearized reading' (duration: 1.630632954s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:12.426506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:10.79508Z","time spent":"1.63141078s","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T22:13:12.426734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084723757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:13:12.426809Z","caller":"traceutil/trace.go:171","msg":"trace[811507480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"1.084840488s","start":"2026-02-05T22:13:11.34195Z","end":"2026-02-05T22:13:12.42679Z","steps":["trace[811507480] 'agreement among raft nodes before linearized reading' (duration: 1.084749147s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:12.426846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:11.341924Z","time spent":"1.08491257s","remote":"127.0.0.1:33136","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-05T22:13:12.427188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409370372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T22:13:12.427234Z","caller":"traceutil/trace.go:171","msg":"trace[1887733729] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:785; }","duration":"1.409458823s","start":"2026-02-05T22:13:11.017764Z","end":"2026-02-05T22:13:12.427223Z","steps":["trace[1887733729] 'agreement among raft nodes before linearized reading' (duration: 1.409374212s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:12.427265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:11.017747Z","time spent":"1.409510325s","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-02-05T22:13:26.766581Z","caller":"traceutil/trace.go:171","msg":"trace[1792881539] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"159.42707ms","start":"2026-02-05T22:13:26.607136Z","end":"2026-02-05T22:13:26.766563Z","steps":["trace[1792881539] 'read index received' (duration: 159.248367ms)","trace[1792881539] 'applied index is now lower than readState.Index' (duration: 176.373µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:26.766841Z","caller":"traceutil/trace.go:171","msg":"trace[769875446] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"183.583026ms","start":"2026-02-05T22:13:26.58325Z","end":"2026-02-05T22:13:26.766833Z","steps":["trace[769875446] 'process raft request' (duration: 183.209779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:26.767079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.928639ms","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-05T22:13:26.7671Z","caller":"traceutil/trace.go:171","msg":"trace[1334148020] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:880; }","duration":"159.989959ms","start":"2026-02-05T22:13:26.607102Z","end":"2026-02-05T22:13:26.767092Z","steps":["trace[1334148020] 'agreement among raft nodes before linearized reading' (duration: 159.858107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:26.767699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.169515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T22:13:26.767719Z","caller":"traceutil/trace.go:171","msg":"trace[580707293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"159.197495ms","start":"2026-02-05T22:13:26.608515Z","end":"2026-02-05T22:13:26.767712Z","steps":["trace[580707293] 'agreement among raft nodes before linearized reading' (duration: 159.146145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:26.76879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.589638ms","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-05T22:13:26.76881Z","caller":"traceutil/trace.go:171","msg":"trace[2118949964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"161.61784ms","start":"2026-02-05T22:13:26.607187Z","end":"2026-02-05T22:13:26.768804Z","steps":["trace[2118949964] 'agreement among raft nodes before linearized reading' (duration: 159.98887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:26.768922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.66486ms","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-05T22:13:26.768974Z","caller":"traceutil/trace.go:171","msg":"trace[1460844506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"161.750472ms","start":"2026-02-05T22:13:26.60721Z","end":"2026-02-05T22:13:26.76896Z","steps":["trace[1460844506] 'agreement among raft nodes before linearized reading' (duration: 161.556688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:29.327098Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360082920511886,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T22:13:29.475522Z","caller":"traceutil/trace.go:171","msg":"trace[743343724] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"649.462784ms","start":"2026-02-05T22:13:28.826045Z","end":"2026-02-05T22:13:29.475507Z","steps":["trace[743343724] 'read index received' (duration: 649.315751ms)","trace[743343724] 'applied index is now lower than readState.Index' (duration: 146.433µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:29.475701Z","caller":"traceutil/trace.go:171","msg":"trace[829626548] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"702.944554ms","start":"2026-02-05T22:13:28.77275Z","end":"2026-02-05T22:13:29.475694Z","steps":["trace[829626548] 'process raft request' (duration: 702.666488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:29.475775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:28.77274Z","time spent":"702.980424ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:13:29.47594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.895322ms","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-05T22:13:29.475956Z","caller":"traceutil/trace.go:171","msg":"trace[419538903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:886; }","duration":"649.935673ms","start":"2026-02-05T22:13:28.826016Z","end":"2026-02-05T22:13:29.475952Z","steps":["trace[419538903] 'agreement among raft nodes before linearized reading' (duration: 649.865242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:29.475971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:28.826003Z","time spent":"649.963904ms","remote":"127.0.0.1:33364","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-05T22:13:29.476053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.926165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T22:13:29.476069Z","caller":"traceutil/trace.go:171","msg":"trace[1359655465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"648.947105ms","start":"2026-02-05T22:13:28.827114Z","end":"2026-02-05T22:13:29.476062Z","steps":["trace[1359655465] 'agreement among raft nodes before linearized reading' (duration: 648.911945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:29.476087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:28.82711Z","time spent":"648.972586ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T22:13:29.47617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.843157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:13:29.476184Z","caller":"traceutil/trace.go:171","msg":"trace[1290370169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"139.884177ms","start":"2026-02-05T22:13:29.336297Z","end":"2026-02-05T22:13:29.476181Z","steps":["trace[1290370169] 'agreement among raft nodes before linearized reading' (duration: 139.863317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:29.476253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.823899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:13:29.476267Z","caller":"traceutil/trace.go:171","msg":"trace[1584786903] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:886; }","duration":"624.849859ms","start":"2026-02-05T22:13:28.851412Z","end":"2026-02-05T22:13:29.476261Z","steps":["trace[1584786903] 'agreement among raft nodes before linearized reading' (duration: 624.827518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:29.476278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:28.851406Z","time spent":"624.869359ms","remote":"127.0.0.1:33610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-05T22:13:29.476375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.66404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T22:13:29.476388Z","caller":"traceutil/trace.go:171","msg":"trace[1841675570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:886; }","duration":"648.68408ms","start":"2026-02-05T22:13:28.827699Z","end":"2026-02-05T22:13:29.476383Z","steps":["trace[1841675570] 'agreement among raft nodes before linearized reading' (duration: 648.639169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:29.4764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:28.827695Z","time spent":"648.70206ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T22:13:29.476471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.324702ms","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-05T22:13:29.476488Z","caller":"traceutil/trace.go:171","msg":"trace[460851266] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"649.347423ms","start":"2026-02-05T22:13:28.827137Z","end":"2026-02-05T22:13:29.476484Z","steps":["trace[460851266] 'agreement among raft nodes before linearized reading' (duration: 649.307732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:29.4765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:28.827134Z","time spent":"649.362373ms","remote":"127.0.0.1:33364","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-05T22:13:29.982777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360082920511890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T22:13:30.015048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.043427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:13:30.01513Z","caller":"traceutil/trace.go:171","msg":"trace[39053560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"535.156589ms","start":"2026-02-05T22:13:29.479952Z","end":"2026-02-05T22:13:30.015109Z","steps":["trace[39053560] 'range keys from in-memory index tree' (duration: 535.001757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:30.015167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:29.479943Z","time spent":"535.217061ms","remote":"127.0.0.1:33122","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-05T22:13:30.115232Z","caller":"traceutil/trace.go:171","msg":"trace[1281564404] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"633.567239ms","start":"2026-02-05T22:13:29.481649Z","end":"2026-02-05T22:13:30.115216Z","steps":["trace[1281564404] 'process raft request' (duration: 633.469288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:30.115331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:29.481639Z","time spent":"633.638141ms","remote":"127.0.0.1:33364","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-05T22:13:30.156363Z","caller":"traceutil/trace.go:171","msg":"trace[305167752] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"674.568789ms","start":"2026-02-05T22:13:29.481772Z","end":"2026-02-05T22:13:30.156341Z","steps":["trace[305167752] 'process raft request' (duration: 674.393486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:30.156483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:29.481768Z","time spent":"674.64839ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:13:30.15676Z","caller":"traceutil/trace.go:171","msg":"trace[515426108] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"672.755944ms","start":"2026-02-05T22:13:29.483999Z","end":"2026-02-05T22:13:30.156755Z","steps":["trace[515426108] 'process raft request' (duration: 672.281136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:30.156792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:29.483976Z","time spent":"672.796066ms","remote":"127.0.0.1:33364","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-05T22:13:30.156892Z","caller":"traceutil/trace.go:171","msg":"trace[223537357] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:923; }","duration":"674.154541ms","start":"2026-02-05T22:13:29.482732Z","end":"2026-02-05T22:13:30.156887Z","steps":["trace[223537357] 'read index received' (duration: 632.863247ms)","trace[223537357] 'applied index is now lower than readState.Index' (duration: 41.290604ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:30.156945Z","caller":"traceutil/trace.go:171","msg":"trace[1452295621] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"672.491659ms","start":"2026-02-05T22:13:29.484448Z","end":"2026-02-05T22:13:30.15694Z","steps":["trace[1452295621] 'process raft request' (duration: 671.853908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:30.156967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:29.484442Z","time spent":"672.51363ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:13:30.157052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.190861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T22:13:30.157071Z","caller":"traceutil/trace.go:171","msg":"trace[700595688] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:890; }","duration":"674.343954ms","start":"2026-02-05T22:13:29.482721Z","end":"2026-02-05T22:13:30.157065Z","steps":["trace[700595688] 'agreement among raft nodes before linearized reading' (duration: 674.184311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:30.157091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:29.482715Z","time spent":"674.368734ms","remote":"127.0.0.1:33370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-02-05T22:13:32.305661Z","caller":"traceutil/trace.go:171","msg":"trace[1528473656] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"181.754343ms","start":"2026-02-05T22:13:32.123892Z","end":"2026-02-05T22:13:32.305646Z","steps":["trace[1528473656] 'process raft request' (duration: 181.63147ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:32.447314Z","caller":"traceutil/trace.go:171","msg":"trace[2078543504] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"285.484182ms","start":"2026-02-05T22:13:32.161813Z","end":"2026-02-05T22:13:32.447298Z","steps":["trace[2078543504] 'read index received' (duration: 146.153395ms)","trace[2078543504] 'applied index is now lower than readState.Index' (duration: 139.330107ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:32.447465Z","caller":"traceutil/trace.go:171","msg":"trace[408133339] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"305.063424ms","start":"2026-02-05T22:13:32.142396Z","end":"2026-02-05T22:13:32.447459Z","steps":["trace[408133339] 'process raft request' (duration: 304.799289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:32.447557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:32.142374Z","time spent":"305.122674ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:13:32.447937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.121604ms","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-05T22:13:32.447957Z","caller":"traceutil/trace.go:171","msg":"trace[1331304363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:893; }","duration":"286.178224ms","start":"2026-02-05T22:13:32.161773Z","end":"2026-02-05T22:13:32.447952Z","steps":["trace[1331304363] 'agreement among raft nodes before linearized reading' (duration: 285.889538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:32.448095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.210128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T22:13:32.448112Z","caller":"traceutil/trace.go:171","msg":"trace[641246780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:893; }","duration":"284.245978ms","start":"2026-02-05T22:13:32.16386Z","end":"2026-02-05T22:13:32.448106Z","steps":["trace[641246780] 'agreement among raft nodes before linearized reading' (duration: 284.187147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:32.44827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.621755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:13:32.448285Z","caller":"traceutil/trace.go:171","msg":"trace[1719197667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"111.666646ms","start":"2026-02-05T22:13:32.336614Z","end":"2026-02-05T22:13:32.44828Z","steps":["trace[1719197667] 'agreement among raft nodes before linearized reading' (duration: 111.588595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:32.448404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.36774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T22:13:32.448421Z","caller":"traceutil/trace.go:171","msg":"trace[1735401085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:893; }","duration":"284.401491ms","start":"2026-02-05T22:13:32.164013Z","end":"2026-02-05T22:13:32.448414Z","steps":["trace[1735401085] 'agreement among raft nodes before linearized reading' (duration: 284.34461ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:34.820476Z","caller":"traceutil/trace.go:171","msg":"trace[696081039] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"484.804879ms","start":"2026-02-05T22:13:34.335653Z","end":"2026-02-05T22:13:34.820458Z","steps":["trace[696081039] 'read index received' (duration: 484.653586ms)","trace[696081039] 'applied index is now lower than readState.Index' (duration: 150.763µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:34.82073Z","caller":"traceutil/trace.go:171","msg":"trace[1888779075] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"508.042109ms","start":"2026-02-05T22:13:34.312682Z","end":"2026-02-05T22:13:34.820724Z","steps":["trace[1888779075] 'process raft request' (duration: 507.682883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:34.820808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:34.312672Z","time spent":"508.08029ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:13:34.82092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.265147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:13:34.820942Z","caller":"traceutil/trace.go:171","msg":"trace[1531281537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"485.297527ms","start":"2026-02-05T22:13:34.335638Z","end":"2026-02-05T22:13:34.820935Z","steps":["trace[1531281537] 'agreement among raft nodes before linearized reading' (duration: 485.267957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:34.820959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:34.335632Z","time spent":"485.323857ms","remote":"127.0.0.1:33136","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-05T22:13:34.82109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.318058ms","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-05T22:13:34.821106Z","caller":"traceutil/trace.go:171","msg":"trace[914627344] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:898; }","duration":"365.373568ms","start":"2026-02-05T22:13:34.455729Z","end":"2026-02-05T22:13:34.821102Z","steps":["trace[914627344] 'agreement among raft nodes before linearized reading' (duration: 365.309527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:34.821121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:34.455712Z","time spent":"365.406769ms","remote":"127.0.0.1:33364","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-05T22:13:34.821238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.290231ms","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-05T22:13:34.821255Z","caller":"traceutil/trace.go:171","msg":"trace[1392696122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:898; }","duration":"350.329082ms","start":"2026-02-05T22:13:34.470922Z","end":"2026-02-05T22:13:34.821251Z","steps":["trace[1392696122] 'agreement among raft nodes before linearized reading' (duration: 350.286961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:34.821271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:34.470911Z","time spent":"350.355442ms","remote":"127.0.0.1:33364","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-05T22:13:34.821349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.828242ms","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-05T22:13:34.821364Z","caller":"traceutil/trace.go:171","msg":"trace[1982377542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:898; }","duration":"355.864704ms","start":"2026-02-05T22:13:34.465496Z","end":"2026-02-05T22:13:34.821361Z","steps":["trace[1982377542] 'agreement among raft nodes before linearized reading' (duration: 355.820043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:34.821378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:34.465486Z","time spent":"355.889965ms","remote":"127.0.0.1:33364","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-05T22:13:35.023986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.246948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:13:35.024095Z","caller":"traceutil/trace.go:171","msg":"trace[1803761252] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"198.826108ms","start":"2026-02-05T22:13:34.825258Z","end":"2026-02-05T22:13:35.024084Z","steps":["trace[1803761252] 'process raft request' (duration: 81.423007ms)","trace[1803761252] 'compare' (duration: 117.173857ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:35.024308Z","caller":"traceutil/trace.go:171","msg":"trace[340124226] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"197.565616ms","start":"2026-02-05T22:13:34.82672Z","end":"2026-02-05T22:13:35.024285Z","steps":["trace[340124226] 'process raft request' (duration: 197.338631ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:35.024359Z","caller":"traceutil/trace.go:171","msg":"trace[186127619] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"195.554878ms","start":"2026-02-05T22:13:34.828798Z","end":"2026-02-05T22:13:35.024353Z","steps":["trace[186127619] 'process raft request' (duration: 195.424046ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:41.146836Z","caller":"traceutil/trace.go:171","msg":"trace[1352187442] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"106.673414ms","start":"2026-02-05T22:13:41.040149Z","end":"2026-02-05T22:13:41.146823Z","steps":["trace[1352187442] 'read index received' (duration: 106.576512ms)","trace[1352187442] 'applied index is now lower than readState.Index' (duration: 96.262µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:41.146906Z","caller":"traceutil/trace.go:171","msg":"trace[1954264832] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"302.816771ms","start":"2026-02-05T22:13:40.844084Z","end":"2026-02-05T22:13:41.146901Z","steps":["trace[1954264832] 'process raft request' (duration: 302.647208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:41.146973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:40.844068Z","time spent":"302.850162ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:13:41.147058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.30136ms","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-05T22:13:41.147116Z","caller":"traceutil/trace.go:171","msg":"trace[1050523556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"104.374731ms","start":"2026-02-05T22:13:41.042715Z","end":"2026-02-05T22:13:41.14709Z","steps":["trace[1050523556] 'agreement among raft nodes before linearized reading' (duration: 104.262269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:41.147184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.045141ms","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-05T22:13:41.147203Z","caller":"traceutil/trace.go:171","msg":"trace[148580838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:915; }","duration":"107.099021ms","start":"2026-02-05T22:13:41.040099Z","end":"2026-02-05T22:13:41.147198Z","steps":["trace[148580838] 'agreement among raft nodes before linearized reading' (duration: 107.03343ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:41.31288Z","caller":"traceutil/trace.go:171","msg":"trace[980268157] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"161.498168ms","start":"2026-02-05T22:13:41.151366Z","end":"2026-02-05T22:13:41.312864Z","steps":["trace[980268157] 'process raft request' (duration: 161.404886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:41.674018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.814562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:13:41.674186Z","caller":"traceutil/trace.go:171","msg":"trace[1550746425] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:954; }","duration":"331.941831ms","start":"2026-02-05T22:13:41.342229Z","end":"2026-02-05T22:13:41.674171Z","steps":["trace[1550746425] 'read index received' (duration: 128.834293ms)","trace[1550746425] 'applied index is now lower than readState.Index' (duration: 203.106698ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:41.674439Z","caller":"traceutil/trace.go:171","msg":"trace[278601141] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"522.559207ms","start":"2026-02-05T22:13:41.151849Z","end":"2026-02-05T22:13:41.674408Z","steps":["trace[278601141] 'process raft request' (duration: 319.267616ms)","trace[278601141] 'compare' (duration: 202.615009ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:13:41.674505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:41.151833Z","time spent":"522.639528ms","remote":"127.0.0.1:33364","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-05T22:13:41.674689Z","caller":"traceutil/trace.go:171","msg":"trace[1168768010] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"520.924907ms","start":"2026-02-05T22:13:41.153753Z","end":"2026-02-05T22:13:41.674678Z","steps":["trace[1168768010] 'process raft request' (duration: 520.367517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:41.674744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:41.153746Z","time spent":"520.971297ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:13:41.674878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.646564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:13:41.67491Z","caller":"traceutil/trace.go:171","msg":"trace[1146071237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"332.715676ms","start":"2026-02-05T22:13:41.342186Z","end":"2026-02-05T22:13:41.674901Z","steps":["trace[1146071237] 'agreement among raft nodes before linearized reading' (duration: 332.668415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:41.674941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:41.342152Z","time spent":"332.784246ms","remote":"127.0.0.1:33136","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-05T22:13:59.632876Z","caller":"traceutil/trace.go:171","msg":"trace[486970940] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"125.714635ms","start":"2026-02-05T22:13:59.507115Z","end":"2026-02-05T22:13:59.63283Z","steps":["trace[486970940] 'process raft request' (duration: 125.587472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:01.130429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.221277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-02-05T22:14:01.13047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.946392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:01.130553Z","caller":"traceutil/trace.go:171","msg":"trace[627608243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"791.066674ms","start":"2026-02-05T22:14:00.339467Z","end":"2026-02-05T22:14:01.130534Z","steps":["trace[627608243] 'range keys from in-memory index tree' (duration: 790.8902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:01.13059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:00.339453Z","time spent":"791.128875ms","remote":"127.0.0.1:33136","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-05T22:14:01.130721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.883297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-05T22:14:01.130505Z","caller":"traceutil/trace.go:171","msg":"trace[910212321] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1030; }","duration":"238.339378ms","start":"2026-02-05T22:14:00.892145Z","end":"2026-02-05T22:14:01.130485Z","steps":["trace[910212321] 'range keys from in-memory index tree' (duration: 238.062884ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:01.130776Z","caller":"traceutil/trace.go:171","msg":"trace[1756947474] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1030; }","duration":"579.976419ms","start":"2026-02-05T22:14:00.550769Z","end":"2026-02-05T22:14:01.130745Z","steps":["trace[1756947474] 'range keys from in-memory index tree' (duration: 579.777486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:01.130811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:00.55075Z","time spent":"580.05327ms","remote":"127.0.0.1:33364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-05T22:16:58.706639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.948764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-05T22:16:58.706699Z","caller":"traceutil/trace.go:171","msg":"trace[124434560] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1784; }","duration":"112.048896ms","start":"2026-02-05T22:16:58.594638Z","end":"2026-02-05T22:16:58.706686Z","steps":["trace[124434560] 'range keys from in-memory index tree' (duration: 111.818741ms)"],"step_count":1}