{"level":"warn","ts":"2026-02-02T14:06:15.35007Z","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-02T14:06:15.350151Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33: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-02T14:06:15.350214Z","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-02T14:06:15.350231Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-02-02T14:06:15.350248Z","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-02T14:06:15.351033Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-02-02T14:06:15.351163Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33: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-02T14:06:15.468247Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"40.541113ms"} {"level":"info","ts":"2026-02-02T14:06:15.495996Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-02-02T14:06:15.49608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T14:06:15.496116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-02-02T14:06:15.496127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T14:06:15.496138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-02-02T14:06:15.496186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-02-02T14:06:15.504202Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T14:06:15.506337Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T14:06:15.509635Z","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-02T14:06:15.512991Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T14:06:15.516262Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T14:06:15.527962Z","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-02T14:06:15.527996Z","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-02T14:06:15.528004Z","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-02T14:06:15.528803Z","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-02T14:06:15.529395Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T14:06:15.529419Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T14:06:15.529584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-02-02T14:06:15.529714Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-02-02T14:06:15.529824Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-02-02T14:06:15.529839Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-02-02T14:06:15.796571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T14:06:15.796615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T14:06:15.796656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-02-02T14:06:15.796668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-02-02T14:06:15.796674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-02-02T14:06:15.796683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-02-02T14:06:15.796689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-02-02T14:06:15.797766Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T14:06:15.797877Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T14:06:15.798185Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T14:06:15.798415Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T14:06:15.799866Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"info","ts":"2026-02-02T14:06:15.800203Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T14:06:15.80303Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T14:06:15.804137Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-02T14:06:15.806294Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T14:06:15.806671Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T14:06:15.806756Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-02T14:06:21.590703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.598399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T14:06:21.591075Z","caller":"traceutil/trace.go:171","msg":"trace[1644800836] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"961.748353ms","start":"2026-02-02T14:06:20.629287Z","end":"2026-02-02T14:06:21.591035Z","steps":["trace[1644800836] 'process raft request' (duration: 433.370263ms)","trace[1644800836] 'compare' (duration: 527.462606ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T14:06:21.591179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:20.629259Z","time spent":"961.901208ms","remote":"127.0.0.1:59574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T14:06:21.660326Z","caller":"traceutil/trace.go:171","msg":"trace[533238990] linearizableReadLoop","detail":"{readStateIndex:260; appliedIndex:257; }","duration":"663.056127ms","start":"2026-02-02T14:06:20.997235Z","end":"2026-02-02T14:06:21.660291Z","steps":["trace[533238990] 'read index received' (duration: 65.373095ms)","trace[533238990] 'applied index is now lower than readState.Index' (duration: 597.681612ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T14:06:21.660356Z","caller":"traceutil/trace.go:171","msg":"trace[651685614] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"979.106898ms","start":"2026-02-02T14:06:20.681212Z","end":"2026-02-02T14:06:21.660319Z","steps":["trace[651685614] 'process raft request' (duration: 978.879422ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:06:21.660476Z","caller":"traceutil/trace.go:171","msg":"trace[1110548288] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"714.957594ms","start":"2026-02-02T14:06:20.945506Z","end":"2026-02-02T14:06:21.660463Z","steps":["trace[1110548288] 'process raft request' (duration: 714.732618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:06:21.660599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:20.945478Z","time spent":"715.032445ms","remote":"127.0.0.1:59690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2883,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T14:06:21.660762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:20.681186Z","time spent":"979.265551ms","remote":"127.0.0.1:59690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5855,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T14:06:21.661171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.91276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T14:06:21.661227Z","caller":"traceutil/trace.go:171","msg":"trace[1867853057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:254; }","duration":"664.026693ms","start":"2026-02-02T14:06:20.997184Z","end":"2026-02-02T14:06:21.661211Z","steps":["trace[1867853057] 'agreement among raft nodes before linearized reading' (duration: 663.838207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:06:21.661286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:20.99716Z","time spent":"664.094305ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-02T14:06:42.123316Z","caller":"traceutil/trace.go:171","msg":"trace[1813620550] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"129.820857ms","start":"2026-02-02T14:06:41.993466Z","end":"2026-02-02T14:06:42.123287Z","steps":["trace[1813620550] 'process raft request' (duration: 129.618851ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:06:47.975314Z","caller":"traceutil/trace.go:171","msg":"trace[335639528] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"156.998162ms","start":"2026-02-02T14:06:47.818294Z","end":"2026-02-02T14:06:47.975292Z","steps":["trace[335639528] 'read index received' (duration: 156.822817ms)","trace[335639528] 'applied index is now lower than readState.Index' (duration: 174.515µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T14:06:47.975491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.187647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-02-02T14:06:47.975528Z","caller":"traceutil/trace.go:171","msg":"trace[1413480728] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:481; }","duration":"157.30223ms","start":"2026-02-02T14:06:47.818214Z","end":"2026-02-02T14:06:47.975516Z","steps":["trace[1413480728] 'agreement among raft nodes before linearized reading' (duration: 157.172956ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:06:47.975849Z","caller":"traceutil/trace.go:171","msg":"trace[260620481] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"161.023707ms","start":"2026-02-02T14:06:47.814815Z","end":"2026-02-02T14:06:47.975839Z","steps":["trace[260620481] 'process raft request' (duration: 160.3618ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:06:51.231392Z","caller":"traceutil/trace.go:171","msg":"trace[1342884523] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"116.350372ms","start":"2026-02-02T14:06:51.114755Z","end":"2026-02-02T14:06:51.231105Z","steps":["trace[1342884523] 'process raft request' (duration: 116.162057ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:06:53.506219Z","caller":"traceutil/trace.go:171","msg":"trace[1652353046] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"152.76212ms","start":"2026-02-02T14:06:53.353439Z","end":"2026-02-02T14:06:53.506201Z","steps":["trace[1652353046] 'process raft request' (duration: 152.651667ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:06:54.439671Z","caller":"traceutil/trace.go:171","msg":"trace[1891018314] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"252.850544ms","start":"2026-02-02T14:06:54.186803Z","end":"2026-02-02T14:06:54.439653Z","steps":["trace[1891018314] 'read index received' (duration: 252.751391ms)","trace[1891018314] 'applied index is now lower than readState.Index' (duration: 98.283µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T14:06:54.439768Z","caller":"traceutil/trace.go:171","msg":"trace[701156022] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"435.166272ms","start":"2026-02-02T14:06:54.004596Z","end":"2026-02-02T14:06:54.439762Z","steps":["trace[701156022] 'process raft request' (duration: 434.954706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:06:54.439992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:54.004586Z","time spent":"435.198272ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T14:06:54.440096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.292885ms","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-02T14:06:54.440116Z","caller":"traceutil/trace.go:171","msg":"trace[1051380555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"253.336796ms","start":"2026-02-02T14:06:54.186773Z","end":"2026-02-02T14:06:54.44011Z","steps":["trace[1051380555] 'agreement among raft nodes before linearized reading' (duration: 253.294045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:06:54.440186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.274266ms","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-02T14:06:54.440202Z","caller":"traceutil/trace.go:171","msg":"trace[1557422759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"117.314437ms","start":"2026-02-02T14:06:54.322882Z","end":"2026-02-02T14:06:54.440196Z","steps":["trace[1557422759] 'agreement among raft nodes before linearized reading' (duration: 117.285736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:06:54.440256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.694823ms","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-02T14:06:54.44027Z","caller":"traceutil/trace.go:171","msg":"trace[1546422221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"113.720373ms","start":"2026-02-02T14:06:54.326546Z","end":"2026-02-02T14:06:54.440266Z","steps":["trace[1546422221] 'agreement among raft nodes before linearized reading' (duration: 113.696742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:06:54.77097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.144378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T14:06:54.771062Z","caller":"traceutil/trace.go:171","msg":"trace[524030228] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"321.379607ms","start":"2026-02-02T14:06:54.44967Z","end":"2026-02-02T14:06:54.77105Z","steps":["trace[524030228] 'process raft request' (duration: 146.084305ms)","trace[524030228] 'compare' (duration: 175.034135ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T14:06:54.771097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:54.449657Z","time spent":"321.426228ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T14:06:54.784846Z","caller":"traceutil/trace.go:171","msg":"trace[1057536470] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"335.079918ms","start":"2026-02-02T14:06:54.449747Z","end":"2026-02-02T14:06:54.784827Z","steps":["trace[1057536470] 'process raft request' (duration: 334.646767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:06:54.785018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:54.449741Z","time spent":"335.215222ms","remote":"127.0.0.1:34212","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-02T14:06:54.787366Z","caller":"traceutil/trace.go:171","msg":"trace[1641034040] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"336.796843ms","start":"2026-02-02T14:06:54.450551Z","end":"2026-02-02T14:06:54.787347Z","steps":["trace[1641034040] 'process raft request' (duration: 333.923447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:06:54.787471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:54.450535Z","time spent":"336.878015ms","remote":"127.0.0.1:34212","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-02T14:06:59.302524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154228621167775,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T14:06:59.593915Z","caller":"traceutil/trace.go:171","msg":"trace[855963105] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"806.924556ms","start":"2026-02-02T14:06:58.78696Z","end":"2026-02-02T14:06:59.593884Z","steps":["trace[855963105] 'process raft request' (duration: 806.785963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:06:59.594087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:58.78694Z","time spent":"807.051379ms","remote":"127.0.0.1:34212","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-02T14:07:00.305529Z","caller":"traceutil/trace.go:171","msg":"trace[797640760] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:561; }","duration":"1.50390982s","start":"2026-02-02T14:06:58.801579Z","end":"2026-02-02T14:07:00.305489Z","steps":["trace[797640760] 'read index received' (duration: 792.112416ms)","trace[797640760] 'applied index is now lower than readState.Index' (duration: 711.796144ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T14:07:00.305783Z","caller":"traceutil/trace.go:171","msg":"trace[526756498] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.084757398s","start":"2026-02-02T14:06:59.221015Z","end":"2026-02-02T14:07:00.305772Z","steps":["trace[526756498] 'process raft request' (duration: 1.084336647s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:00.305902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:59.220994Z","time spent":"1.08481635s","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T14:07:00.305917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.503433577s","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-02T14:07:00.30596Z","caller":"traceutil/trace.go:171","msg":"trace[74628337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"1.50352805s","start":"2026-02-02T14:06:58.802419Z","end":"2026-02-02T14:07:00.305947Z","steps":["trace[74628337] 'agreement among raft nodes before linearized reading' (duration: 1.503385786s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:00.305998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:58.802413Z","time spent":"1.503576571s","remote":"127.0.0.1:34212","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-02T14:07:00.306114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.504531506s","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-02T14:07:00.306139Z","caller":"traceutil/trace.go:171","msg":"trace[1752764907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"1.504577058s","start":"2026-02-02T14:06:58.801554Z","end":"2026-02-02T14:07:00.306131Z","steps":["trace[1752764907] 'agreement among raft nodes before linearized reading' (duration: 1.504505986s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:00.306163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:58.801546Z","time spent":"1.504612368s","remote":"127.0.0.1:34212","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-02T14:07:00.306311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.001136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T14:07:00.306348Z","caller":"traceutil/trace.go:171","msg":"trace[2057425231] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:545; }","duration":"786.073778ms","start":"2026-02-02T14:06:59.520264Z","end":"2026-02-02T14:07:00.306338Z","steps":["trace[2057425231] 'agreement among raft nodes before linearized reading' (duration: 786.009846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:00.306377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:59.52025Z","time spent":"786.120389ms","remote":"127.0.0.1:59506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.33\" "} {"level":"warn","ts":"2026-02-02T14:07:00.306377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242577892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lcv9k\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-02T14:07:00.306407Z","caller":"traceutil/trace.go:171","msg":"trace[1507328859] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lcv9k; range_end:; response_count:1; response_revision:545; }","duration":"1.242641624s","start":"2026-02-02T14:06:59.063757Z","end":"2026-02-02T14:07:00.306398Z","steps":["trace[1507328859] 'agreement among raft nodes before linearized reading' (duration: 1.242581772s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:00.306455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:59.063741Z","time spent":"1.242707545s","remote":"127.0.0.1:59690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-lcv9k\" "} {"level":"warn","ts":"2026-02-02T14:07:00.306538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.249618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T14:07:00.30658Z","caller":"traceutil/trace.go:171","msg":"trace[50878821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"548.32027ms","start":"2026-02-02T14:06:59.758252Z","end":"2026-02-02T14:07:00.306572Z","steps":["trace[50878821] 'agreement among raft nodes before linearized reading' (duration: 548.263699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:00.306606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:06:59.758215Z","time spent":"548.385042ms","remote":"127.0.0.1:59482","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-02T14:07:02.608088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.893923ms","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-02T14:07:02.608175Z","caller":"traceutil/trace.go:171","msg":"trace[84435276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"281.000405ms","start":"2026-02-02T14:07:02.327153Z","end":"2026-02-02T14:07:02.608153Z","steps":["trace[84435276] 'range keys from in-memory index tree' (duration: 280.741018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:02.608339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.874176ms","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-02T14:07:02.608435Z","caller":"traceutil/trace.go:171","msg":"trace[508917312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"136.04538ms","start":"2026-02-02T14:07:02.472364Z","end":"2026-02-02T14:07:02.608409Z","steps":["trace[508917312] 'range keys from in-memory index tree' (duration: 135.532497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:02.608585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.395012ms","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-02T14:07:02.608622Z","caller":"traceutil/trace.go:171","msg":"trace[337061232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"282.462564ms","start":"2026-02-02T14:07:02.326149Z","end":"2026-02-02T14:07:02.608611Z","steps":["trace[337061232] 'range keys from in-memory index tree' (duration: 282.218048ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:02.749393Z","caller":"traceutil/trace.go:171","msg":"trace[1441666392] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"132.98368ms","start":"2026-02-02T14:07:02.616389Z","end":"2026-02-02T14:07:02.749373Z","steps":["trace[1441666392] 'process raft request' (duration: 132.950849ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:02.749566Z","caller":"traceutil/trace.go:171","msg":"trace[1244112044] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"134.994003ms","start":"2026-02-02T14:07:02.614565Z","end":"2026-02-02T14:07:02.749559Z","steps":["trace[1244112044] 'process raft request' (duration: 134.597253ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:02.74963Z","caller":"traceutil/trace.go:171","msg":"trace[1805828139] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"133.530144ms","start":"2026-02-02T14:07:02.616092Z","end":"2026-02-02T14:07:02.749623Z","steps":["trace[1805828139] 'process raft request' (duration: 133.218245ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:13.888937Z","caller":"traceutil/trace.go:171","msg":"trace[1338102109] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"119.628878ms","start":"2026-02-02T14:07:13.76929Z","end":"2026-02-02T14:07:13.888919Z","steps":["trace[1338102109] 'process raft request' (duration: 119.504335ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:14.551637Z","caller":"traceutil/trace.go:171","msg":"trace[1929642808] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"204.997455ms","start":"2026-02-02T14:07:14.346621Z","end":"2026-02-02T14:07:14.551618Z","steps":["trace[1929642808] 'process raft request' (duration: 204.880942ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:14.557374Z","caller":"traceutil/trace.go:171","msg":"trace[103557502] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"203.751452ms","start":"2026-02-02T14:07:14.353614Z","end":"2026-02-02T14:07:14.557366Z","steps":["trace[103557502] 'process raft request' (duration: 203.722911ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:14.55754Z","caller":"traceutil/trace.go:171","msg":"trace[1734659231] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"210.024228ms","start":"2026-02-02T14:07:14.347511Z","end":"2026-02-02T14:07:14.557535Z","steps":["trace[1734659231] 'process raft request' (duration: 209.686829ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:14.557655Z","caller":"traceutil/trace.go:171","msg":"trace[1326573322] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"207.252205ms","start":"2026-02-02T14:07:14.350398Z","end":"2026-02-02T14:07:14.55765Z","steps":["trace[1326573322] 'process raft request' (duration: 206.849704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:20.007551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.40417ms","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-02T14:07:20.007694Z","caller":"traceutil/trace.go:171","msg":"trace[575623702] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:734; }","duration":"223.629585ms","start":"2026-02-02T14:07:19.784021Z","end":"2026-02-02T14:07:20.007651Z","steps":["trace[575623702] 'count revisions from in-memory index tree' (duration: 223.304387ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:20.774491Z","caller":"traceutil/trace.go:171","msg":"trace[1821439681] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"761.099939ms","start":"2026-02-02T14:07:20.013361Z","end":"2026-02-02T14:07:20.774461Z","steps":["trace[1821439681] 'process raft request' (duration: 760.883494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:20.774713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:20.013321Z","time spent":"761.283904ms","remote":"127.0.0.1:34212","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-02T14:07:21.219104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.686517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T14:07:21.219186Z","caller":"traceutil/trace.go:171","msg":"trace[409744589] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:758; }","duration":"462.268136ms","start":"2026-02-02T14:07:20.756904Z","end":"2026-02-02T14:07:21.219172Z","steps":["trace[409744589] 'read index received' (duration: 17.350637ms)","trace[409744589] 'applied index is now lower than readState.Index' (duration: 444.916549ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T14:07:21.219371Z","caller":"traceutil/trace.go:171","msg":"trace[615635186] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"685.713516ms","start":"2026-02-02T14:07:20.533649Z","end":"2026-02-02T14:07:21.219362Z","steps":["trace[615635186] 'process raft request' (duration: 507.69273ms)","trace[615635186] 'compare' (duration: 177.307697ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T14:07:21.219413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:20.533622Z","time spent":"685.768176ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T14:07:21.219515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.613684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T14:07:21.219532Z","caller":"traceutil/trace.go:171","msg":"trace[1170674691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:736; }","duration":"462.662146ms","start":"2026-02-02T14:07:20.756864Z","end":"2026-02-02T14:07:21.219527Z","steps":["trace[1170674691] 'agreement among raft nodes before linearized reading' (duration: 462.627605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:21.219552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:20.756828Z","time spent":"462.720317ms","remote":"127.0.0.1:59482","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-02T14:07:21.219849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.892916ms","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-02T14:07:21.219869Z","caller":"traceutil/trace.go:171","msg":"trace[1752558191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:736; }","duration":"366.956477ms","start":"2026-02-02T14:07:20.852907Z","end":"2026-02-02T14:07:21.219863Z","steps":["trace[1752558191] 'agreement among raft nodes before linearized reading' (duration: 366.868224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:21.21989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:20.85289Z","time spent":"366.995858ms","remote":"127.0.0.1:34212","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-02T14:07:21.220007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.728513ms","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-02T14:07:21.220024Z","caller":"traceutil/trace.go:171","msg":"trace[1547376592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:736; }","duration":"322.746694ms","start":"2026-02-02T14:07:20.897272Z","end":"2026-02-02T14:07:21.220019Z","steps":["trace[1547376592] 'agreement among raft nodes before linearized reading' (duration: 322.695972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:21.220042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:20.897258Z","time spent":"322.778794ms","remote":"127.0.0.1:34212","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-02T14:07:21.220146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.259986ms","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-02T14:07:21.220162Z","caller":"traceutil/trace.go:171","msg":"trace[1388650628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:736; }","duration":"345.348848ms","start":"2026-02-02T14:07:20.874808Z","end":"2026-02-02T14:07:21.220157Z","steps":["trace[1388650628] 'agreement among raft nodes before linearized reading' (duration: 345.303087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:21.220179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:20.87476Z","time spent":"345.414211ms","remote":"127.0.0.1:34212","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-02T14:07:40.051492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.331897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T14:07:40.051815Z","caller":"traceutil/trace.go:171","msg":"trace[1094889765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"296.685866ms","start":"2026-02-02T14:07:39.755113Z","end":"2026-02-02T14:07:40.051798Z","steps":["trace[1094889765] 'range keys from in-memory index tree' (duration: 296.283076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:40.052077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.788272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T14:07:40.05216Z","caller":"traceutil/trace.go:171","msg":"trace[757981451] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:797; }","duration":"283.917464ms","start":"2026-02-02T14:07:39.768222Z","end":"2026-02-02T14:07:40.052139Z","steps":["trace[757981451] 'count revisions from in-memory index tree' (duration: 283.717699ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:41.177089Z","caller":"traceutil/trace.go:171","msg":"trace[609669015] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"253.127307ms","start":"2026-02-02T14:07:40.923942Z","end":"2026-02-02T14:07:41.177069Z","steps":["trace[609669015] 'read index received' (duration: 252.992083ms)","trace[609669015] 'applied index is now lower than readState.Index' (duration: 134.464µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T14:07:41.177177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.217429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T14:07:41.177199Z","caller":"traceutil/trace.go:171","msg":"trace[1501905860] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:798; }","duration":"253.25604ms","start":"2026-02-02T14:07:40.923935Z","end":"2026-02-02T14:07:41.177192Z","steps":["trace[1501905860] 'agreement among raft nodes before linearized reading' (duration: 253.203148ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:41.177299Z","caller":"traceutil/trace.go:171","msg":"trace[57013301] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"317.874956ms","start":"2026-02-02T14:07:40.859407Z","end":"2026-02-02T14:07:41.177282Z","steps":["trace[57013301] 'process raft request' (duration: 317.544868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:41.177398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:40.859394Z","time spent":"317.942618ms","remote":"127.0.0.1:34212","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-02T14:07:45.000009Z","caller":"traceutil/trace.go:171","msg":"trace[665818346] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:837; }","duration":"241.985667ms","start":"2026-02-02T14:07:44.757946Z","end":"2026-02-02T14:07:44.999932Z","steps":["trace[665818346] 'read index received' (duration: 34.468634ms)","trace[665818346] 'applied index is now lower than readState.Index' (duration: 207.515683ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T14:07:45.000156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.186362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T14:07:45.00021Z","caller":"traceutil/trace.go:171","msg":"trace[954300280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"242.286835ms","start":"2026-02-02T14:07:44.757913Z","end":"2026-02-02T14:07:45.0002Z","steps":["trace[954300280] 'agreement among raft nodes before linearized reading' (duration: 242.175822ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:45.000569Z","caller":"traceutil/trace.go:171","msg":"trace[447172818] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"337.739951ms","start":"2026-02-02T14:07:44.662818Z","end":"2026-02-02T14:07:45.000558Z","steps":["trace[447172818] 'process raft request' (duration: 336.973571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:45.000662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:44.662796Z","time spent":"337.805072ms","remote":"127.0.0.1:34212","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-02T14:07:45.428299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.528839ms","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-02T14:07:45.428357Z","caller":"traceutil/trace.go:171","msg":"trace[1088563336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"218.633492ms","start":"2026-02-02T14:07:45.209711Z","end":"2026-02-02T14:07:45.428344Z","steps":["trace[1088563336] 'range keys from in-memory index tree' (duration: 218.403575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:45.42856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.665346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T14:07:45.428581Z","caller":"traceutil/trace.go:171","msg":"trace[2019115466] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:809; }","duration":"126.721217ms","start":"2026-02-02T14:07:45.301854Z","end":"2026-02-02T14:07:45.428575Z","steps":["trace[2019115466] 'count revisions from in-memory index tree' (duration: 126.609274ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:45.652253Z","caller":"traceutil/trace.go:171","msg":"trace[367462967] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"204.191987ms","start":"2026-02-02T14:07:45.447957Z","end":"2026-02-02T14:07:45.652149Z","steps":["trace[367462967] 'process raft request' (duration: 203.801837ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:45.659896Z","caller":"traceutil/trace.go:171","msg":"trace[521637574] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"211.460755ms","start":"2026-02-02T14:07:45.448417Z","end":"2026-02-02T14:07:45.659877Z","steps":["trace[521637574] 'process raft request' (duration: 210.966583ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:45.663209Z","caller":"traceutil/trace.go:171","msg":"trace[685581055] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"214.684608ms","start":"2026-02-02T14:07:45.448462Z","end":"2026-02-02T14:07:45.663146Z","steps":["trace[685581055] 'process raft request' (duration: 211.016493ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:45.663748Z","caller":"traceutil/trace.go:171","msg":"trace[2062056583] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"215.247914ms","start":"2026-02-02T14:07:45.44849Z","end":"2026-02-02T14:07:45.663738Z","steps":["trace[2062056583] 'process raft request' (duration: 211.023554ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:07:58.253221Z","caller":"traceutil/trace.go:171","msg":"trace[974889953] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"484.746834ms","start":"2026-02-02T14:07:57.768448Z","end":"2026-02-02T14:07:58.253195Z","steps":["trace[974889953] 'read index received' (duration: 484.663722ms)","trace[974889953] 'applied index is now lower than readState.Index' (duration: 82.102µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T14:07:58.253362Z","caller":"traceutil/trace.go:171","msg":"trace[1497932889] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"485.076093ms","start":"2026-02-02T14:07:57.768277Z","end":"2026-02-02T14:07:58.253353Z","steps":["trace[1497932889] 'process raft request' (duration: 484.744214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:58.253477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:57.768265Z","time spent":"485.119754ms","remote":"127.0.0.1:34212","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-02T14:07:58.253496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.024091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T14:07:58.253569Z","caller":"traceutil/trace.go:171","msg":"trace[972723996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"485.108333ms","start":"2026-02-02T14:07:57.768445Z","end":"2026-02-02T14:07:58.253554Z","steps":["trace[972723996] 'agreement among raft nodes before linearized reading' (duration: 484.99846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:58.253607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:57.768427Z","time spent":"485.175375ms","remote":"127.0.0.1:59482","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-02T14:07:58.276214Z","caller":"traceutil/trace.go:171","msg":"trace[1704742646] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"507.71795ms","start":"2026-02-02T14:07:57.768478Z","end":"2026-02-02T14:07:58.276196Z","steps":["trace[1704742646] 'process raft request' (duration: 507.435013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:58.276331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:57.768475Z","time spent":"507.788542ms","remote":"127.0.0.1:34212","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-02T14:07:58.276554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.386376ms","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-02T14:07:58.276573Z","caller":"traceutil/trace.go:171","msg":"trace[613700104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:895; }","duration":"449.419648ms","start":"2026-02-02T14:07:57.827147Z","end":"2026-02-02T14:07:58.276566Z","steps":["trace[613700104] 'agreement among raft nodes before linearized reading' (duration: 449.289164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:58.276588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:57.827137Z","time spent":"449.447198ms","remote":"127.0.0.1:34212","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-02T14:07:58.276676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.971058ms","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-02T14:07:58.27669Z","caller":"traceutil/trace.go:171","msg":"trace[646468870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"456.005998ms","start":"2026-02-02T14:07:57.820679Z","end":"2026-02-02T14:07:58.276685Z","steps":["trace[646468870] 'agreement among raft nodes before linearized reading' (duration: 455.966177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:58.276701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:57.820634Z","time spent":"456.06516ms","remote":"127.0.0.1:34212","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-02T14:07:58.276802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.523621ms","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-02T14:07:58.276815Z","caller":"traceutil/trace.go:171","msg":"trace[2056047103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"476.571782ms","start":"2026-02-02T14:07:57.800239Z","end":"2026-02-02T14:07:58.276811Z","steps":["trace[2056047103] 'agreement among raft nodes before linearized reading' (duration: 476.512561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:07:58.276828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:07:57.800225Z","time spent":"476.599882ms","remote":"127.0.0.1:34212","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-02T14:08:08.463962Z","caller":"traceutil/trace.go:171","msg":"trace[706419688] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:957; }","duration":"128.147554ms","start":"2026-02-02T14:08:08.335798Z","end":"2026-02-02T14:08:08.463946Z","steps":["trace[706419688] 'read index received' (duration: 34.195367ms)","trace[706419688] 'applied index is now lower than readState.Index' (duration: 93.951477ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T14:08:08.464046Z","caller":"traceutil/trace.go:171","msg":"trace[361588121] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"148.632376ms","start":"2026-02-02T14:08:08.315406Z","end":"2026-02-02T14:08:08.464039Z","steps":["trace[361588121] 'process raft request' (duration: 148.41736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:08.464154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.571539ms","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-02T14:08:08.464205Z","caller":"traceutil/trace.go:171","msg":"trace[1076915663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"127.655362ms","start":"2026-02-02T14:08:08.336535Z","end":"2026-02-02T14:08:08.464191Z","steps":["trace[1076915663] 'agreement among raft nodes before linearized reading' (duration: 127.519818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:08.464361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.572215ms","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-02T14:08:08.464385Z","caller":"traceutil/trace.go:171","msg":"trace[467869430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:923; }","duration":"128.625567ms","start":"2026-02-02T14:08:08.335751Z","end":"2026-02-02T14:08:08.464376Z","steps":["trace[467869430] 'agreement among raft nodes before linearized reading' (duration: 128.528394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:08.464391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.805587ms","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-02T14:08:08.464422Z","caller":"traceutil/trace.go:171","msg":"trace[990355088] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:923; }","duration":"107.864609ms","start":"2026-02-02T14:08:08.35655Z","end":"2026-02-02T14:08:08.464415Z","steps":["trace[990355088] 'agreement among raft nodes before linearized reading' (duration: 107.779156ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:08:08.642516Z","caller":"traceutil/trace.go:171","msg":"trace[349698973] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"169.871336ms","start":"2026-02-02T14:08:08.472631Z","end":"2026-02-02T14:08:08.642502Z","steps":["trace[349698973] 'process raft request' (duration: 169.843435ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:08:08.642723Z","caller":"traceutil/trace.go:171","msg":"trace[1632351381] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"170.897173ms","start":"2026-02-02T14:08:08.471821Z","end":"2026-02-02T14:08:08.642718Z","steps":["trace[1632351381] 'process raft request' (duration: 164.203979ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:08:10.599013Z","caller":"traceutil/trace.go:171","msg":"trace[806895619] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"129.411547ms","start":"2026-02-02T14:08:10.469586Z","end":"2026-02-02T14:08:10.598997Z","steps":["trace[806895619] 'process raft request' (duration: 129.11264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:11.157718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.959904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T14:08:11.158591Z","caller":"traceutil/trace.go:171","msg":"trace[670673121] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"497.548576ms","start":"2026-02-02T14:08:10.661034Z","end":"2026-02-02T14:08:11.158582Z","steps":["trace[670673121] 'process raft request' (duration: 44.669108ms)","trace[670673121] 'compare' (duration: 451.850871ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T14:08:11.161297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:08:10.661023Z","time spent":"500.232876ms","remote":"127.0.0.1:34212","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-02T14:08:11.159591Z","caller":"traceutil/trace.go:171","msg":"trace[1952434263] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"494.952229ms","start":"2026-02-02T14:08:10.664613Z","end":"2026-02-02T14:08:11.159566Z","steps":["trace[1952434263] 'process raft request' (duration: 493.698077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:11.161636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:08:10.664603Z","time spent":"497.000332ms","remote":"127.0.0.1:34212","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-02T14:08:11.159856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.846876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T14:08:11.161812Z","caller":"traceutil/trace.go:171","msg":"trace[988511295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"405.853028ms","start":"2026-02-02T14:08:10.755948Z","end":"2026-02-02T14:08:11.161801Z","steps":["trace[988511295] 'agreement among raft nodes before linearized reading' (duration: 403.846556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:11.161867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:08:10.755935Z","time spent":"405.92398ms","remote":"127.0.0.1:59482","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-02T14:08:11.158547Z","caller":"traceutil/trace.go:171","msg":"trace[761057835] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:967; }","duration":"402.545652ms","start":"2026-02-02T14:08:10.755989Z","end":"2026-02-02T14:08:11.158534Z","steps":["trace[761057835] 'read index received' (duration: 401.109605ms)","trace[761057835] 'applied index is now lower than readState.Index' (duration: 1.435337ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T14:08:22.833138Z","caller":"traceutil/trace.go:171","msg":"trace[1418319294] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"133.321658ms","start":"2026-02-02T14:08:22.699794Z","end":"2026-02-02T14:08:22.833116Z","steps":["trace[1418319294] 'process raft request' (duration: 67.687445ms)","trace[1418319294] 'compare' (duration: 65.292274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T14:08:24.002696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.319672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T14:08:24.002751Z","caller":"traceutil/trace.go:171","msg":"trace[1574634892] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:993; }","duration":"516.422785ms","start":"2026-02-02T14:08:23.486313Z","end":"2026-02-02T14:08:24.002736Z","steps":["trace[1574634892] 'count revisions from in-memory index tree' (duration: 516.22772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:24.002787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:08:23.486276Z","time spent":"516.500257ms","remote":"127.0.0.1:34362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-02T14:08:24.003053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.501355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T14:08:24.003072Z","caller":"traceutil/trace.go:171","msg":"trace[1638348445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"243.546677ms","start":"2026-02-02T14:08:23.75952Z","end":"2026-02-02T14:08:24.003067Z","steps":["trace[1638348445] 'range keys from in-memory index tree' (duration: 243.444184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:24.884527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.61354ms","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-02T14:08:24.88464Z","caller":"traceutil/trace.go:171","msg":"trace[941185250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:994; }","duration":"228.774474ms","start":"2026-02-02T14:08:24.655846Z","end":"2026-02-02T14:08:24.884621Z","steps":["trace[941185250] 'range keys from in-memory index tree' (duration: 228.434765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:25.82677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.940379ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-02T14:08:25.826989Z","caller":"traceutil/trace.go:171","msg":"trace[1687763771] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"989.517987ms","start":"2026-02-02T14:08:24.837455Z","end":"2026-02-02T14:08:25.826973Z","steps":["trace[1687763771] 'read index received' (duration: 15.42632ms)","trace[1687763771] 'applied index is now lower than readState.Index' (duration: 974.089657ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T14:08:25.827147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.682802ms","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-02T14:08:25.827168Z","caller":"traceutil/trace.go:171","msg":"trace[623469323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"989.718463ms","start":"2026-02-02T14:08:24.837444Z","end":"2026-02-02T14:08:25.827162Z","steps":["trace[623469323] 'agreement among raft nodes before linearized reading' (duration: 989.60831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:25.827194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:08:24.837404Z","time spent":"989.784865ms","remote":"127.0.0.1:34212","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":"info","ts":"2026-02-02T14:08:25.827612Z","caller":"traceutil/trace.go:171","msg":"trace[778784064] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"939.714996ms","start":"2026-02-02T14:08:24.887891Z","end":"2026-02-02T14:08:25.827606Z","steps":["trace[778784064] 'process raft request' (duration: 938.987877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:25.82767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:08:24.887873Z","time spent":"939.759567ms","remote":"127.0.0.1:34212","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-02T14:08:25.827856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.55538ms","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-02T14:08:25.827878Z","caller":"traceutil/trace.go:171","msg":"trace[306520057] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:995; }","duration":"230.614231ms","start":"2026-02-02T14:08:25.597259Z","end":"2026-02-02T14:08:25.827873Z","steps":["trace[306520057] 'agreement among raft nodes before linearized reading' (duration: 230.56335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:25.828052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.867266ms","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-02T14:08:25.828072Z","caller":"traceutil/trace.go:171","msg":"trace[835984667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:995; }","duration":"467.926757ms","start":"2026-02-02T14:08:25.360141Z","end":"2026-02-02T14:08:25.828068Z","steps":["trace[835984667] 'agreement among raft nodes before linearized reading' (duration: 467.881606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:25.828085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:08:25.360117Z","time spent":"467.965419ms","remote":"127.0.0.1:34212","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-02T14:08:25.830121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.35267ms","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-02T14:08:25.830164Z","caller":"traceutil/trace.go:171","msg":"trace[757555447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:995; }","duration":"534.433012ms","start":"2026-02-02T14:08:25.295719Z","end":"2026-02-02T14:08:25.830152Z","steps":["trace[757555447] 'agreement among raft nodes before linearized reading' (duration: 531.734523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:25.830195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:08:25.295702Z","time spent":"534.486334ms","remote":"127.0.0.1:34212","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-02T14:08:26.223833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.542831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T14:08:26.223996Z","caller":"traceutil/trace.go:171","msg":"trace[1316346617] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"382.599184ms","start":"2026-02-02T14:08:25.841384Z","end":"2026-02-02T14:08:26.223983Z","steps":["trace[1316346617] 'process raft request' (duration: 382.571594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:26.224039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:08:25.841374Z","time spent":"382.644605ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T14:08:26.22422Z","caller":"traceutil/trace.go:171","msg":"trace[1313867928] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"385.728665ms","start":"2026-02-02T14:08:25.838484Z","end":"2026-02-02T14:08:26.224213Z","steps":["trace[1313867928] 'process raft request' (duration: 127.774324ms)","trace[1313867928] 'compare' (duration: 257.254773ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T14:08:26.224256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:08:25.838471Z","time spent":"385.766957ms","remote":"127.0.0.1:34212","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-02T14:08:26.224331Z","caller":"traceutil/trace.go:171","msg":"trace[834643408] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"385.749116ms","start":"2026-02-02T14:08:25.838576Z","end":"2026-02-02T14:08:26.224325Z","steps":["trace[834643408] 'process raft request' (duration: 385.324445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:26.224364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:08:25.838573Z","time spent":"385.774017ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T14:08:26.22443Z","caller":"traceutil/trace.go:171","msg":"trace[1749707041] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"384.130924ms","start":"2026-02-02T14:08:25.840294Z","end":"2026-02-02T14:08:26.224425Z","steps":["trace[1749707041] 'process raft request' (duration: 383.641231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:26.22446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:08:25.840288Z","time spent":"384.156014ms","remote":"127.0.0.1:34212","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-02T14:08:26.418942Z","caller":"traceutil/trace.go:171","msg":"trace[781557314] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"139.227051ms","start":"2026-02-02T14:08:26.279696Z","end":"2026-02-02T14:08:26.418923Z","steps":["trace[781557314] 'process raft request' (duration: 138.967465ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:08:26.419229Z","caller":"traceutil/trace.go:171","msg":"trace[91855779] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"166.119819ms","start":"2026-02-02T14:08:26.253101Z","end":"2026-02-02T14:08:26.419221Z","steps":["trace[91855779] 'process raft request' (duration: 162.69107ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:08:31.492503Z","caller":"traceutil/trace.go:171","msg":"trace[793138055] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"255.430306ms","start":"2026-02-02T14:08:31.237055Z","end":"2026-02-02T14:08:31.492486Z","steps":["trace[793138055] 'process raft request' (duration: 255.307183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:31.978678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.046362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T14:08:31.978735Z","caller":"traceutil/trace.go:171","msg":"trace[936463723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"219.139435ms","start":"2026-02-02T14:08:31.759583Z","end":"2026-02-02T14:08:31.978722Z","steps":["trace[936463723] 'range keys from in-memory index tree' (duration: 218.9737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:08:31.97907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.814528ms","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-02T14:08:31.979091Z","caller":"traceutil/trace.go:171","msg":"trace[1287581762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1029; }","duration":"129.868379ms","start":"2026-02-02T14:08:31.849216Z","end":"2026-02-02T14:08:31.979084Z","steps":["trace[1287581762] 'range keys from in-memory index tree' (duration: 129.686094ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T14:11:32.096423Z","caller":"traceutil/trace.go:171","msg":"trace[637657876] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"124.968792ms","start":"2026-02-02T14:11:31.971431Z","end":"2026-02-02T14:11:32.0964Z","steps":["trace[637657876] 'process raft request' (duration: 124.857439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:11:37.888997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.824203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-02T14:11:37.889076Z","caller":"traceutil/trace.go:171","msg":"trace[2141678973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1837; }","duration":"452.934305ms","start":"2026-02-02T14:11:37.43613Z","end":"2026-02-02T14:11:37.889064Z","steps":["trace[2141678973] 'range keys from in-memory index tree' (duration: 452.688789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T14:11:37.889099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T14:11:37.436117Z","time spent":"452.976497ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T14:11:37.889082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.714137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T14:11:37.889153Z","caller":"traceutil/trace.go:171","msg":"trace[1881026756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1837; }","duration":"129.813899ms","start":"2026-02-02T14:11:37.759319Z","end":"2026-02-02T14:11:37.889133Z","steps":["trace[1881026756] 'range keys from in-memory index tree' (duration: 129.660535ms)"],"step_count":1}