{"level":"warn","ts":"2026-02-04T01:55:16.844143Z","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-04T01:55:16.844236Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-04T01:55:16.844311Z","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-04T01:55:16.844322Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-02-04T01:55:16.844346Z","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-04T01:55:16.845115Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-02-04T01:55:16.845205Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-04T01:55:16.849675Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.235877ms"} {"level":"info","ts":"2026-02-04T01:55:16.857261Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-02-04T01:55:16.857308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T01:55:16.85733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-02-04T01:55:16.857339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T01:55:16.857347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-02-04T01:55:16.857379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-02-04T01:55:16.861821Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T01:55:16.888324Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T01:55:16.891169Z","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-04T01:55:16.892753Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T01:55:16.894441Z","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-04T01:55:16.894569Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T01:55:16.894584Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T01:55:16.89471Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T01:55:16.894808Z","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-04T01:55:16.894841Z","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-04T01:55:16.894847Z","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-04T01:55:16.895454Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-02-04T01:55:16.895462Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-02-04T01:55:16.896932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-02-04T01:55:16.897069Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-02-04T01:55:16.959227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T01:55:16.959348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T01:55:16.959451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-02-04T01:55:16.959514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-02-04T01:55:16.959553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-02-04T01:55:16.959636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-02-04T01:55:16.959689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-02-04T01:55:16.963587Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:55:16.971535Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T01:55:16.971718Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:55:16.973723Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-02-04T01:55:16.974224Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T01:55:16.97926Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T01:55:16.979346Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T01:55:16.984344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T01:55:16.997464Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:55:16.997605Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:55:17.003203Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T01:55:26.680947Z","caller":"traceutil/trace.go:171","msg":"trace[197383029] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"120.430347ms","start":"2026-02-04T01:55:26.560503Z","end":"2026-02-04T01:55:26.680934Z","steps":["trace[197383029] 'process raft request' (duration: 120.320614ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:47.688459Z","caller":"traceutil/trace.go:171","msg":"trace[1133910041] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"123.464826ms","start":"2026-02-04T01:55:47.564977Z","end":"2026-02-04T01:55:47.688441Z","steps":["trace[1133910041] 'process raft request' (duration: 123.372174ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:48.406029Z","caller":"traceutil/trace.go:171","msg":"trace[467400875] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"154.229851ms","start":"2026-02-04T01:55:48.251784Z","end":"2026-02-04T01:55:48.406014Z","steps":["trace[467400875] 'process raft request' (duration: 154.159439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:53.622034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.638369ms","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-04T01:55:53.62214Z","caller":"traceutil/trace.go:171","msg":"trace[1371860232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:488; }","duration":"201.826174ms","start":"2026-02-04T01:55:53.420294Z","end":"2026-02-04T01:55:53.622121Z","steps":["trace[1371860232] 'range keys from in-memory index tree' (duration: 201.448096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:53.622144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.3905ms","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-04T01:55:53.622221Z","caller":"traceutil/trace.go:171","msg":"trace[1754944513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:488; }","duration":"195.517133ms","start":"2026-02-04T01:55:53.426687Z","end":"2026-02-04T01:55:53.622204Z","steps":["trace[1754944513] 'range keys from in-memory index tree' (duration: 195.315949ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:53.801825Z","caller":"traceutil/trace.go:171","msg":"trace[730773239] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"174.445066ms","start":"2026-02-04T01:55:53.627363Z","end":"2026-02-04T01:55:53.801808Z","steps":["trace[730773239] 'process raft request' (duration: 174.305162ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:53.87597Z","caller":"traceutil/trace.go:171","msg":"trace[1961623042] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:509; }","duration":"168.146267ms","start":"2026-02-04T01:55:53.707806Z","end":"2026-02-04T01:55:53.875953Z","steps":["trace[1961623042] 'read index received' (duration: 93.845332ms)","trace[1961623042] 'applied index is now lower than readState.Index' (duration: 74.299925ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:55:53.876083Z","caller":"traceutil/trace.go:171","msg":"trace[188248337] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"246.939015ms","start":"2026-02-04T01:55:53.629118Z","end":"2026-02-04T01:55:53.876057Z","steps":["trace[188248337] 'process raft request' (duration: 202.150513ms)","trace[188248337] 'compare' (duration: 44.553075ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:55:53.876221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.418393ms","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-04T01:55:53.876276Z","caller":"traceutil/trace.go:171","msg":"trace[1859547741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:490; }","duration":"168.506065ms","start":"2026-02-04T01:55:53.707733Z","end":"2026-02-04T01:55:53.87624Z","steps":["trace[1859547741] 'agreement among raft nodes before linearized reading' (duration: 168.339501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:55:53.876471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.513717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7vq9t\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-04T01:55:53.876511Z","caller":"traceutil/trace.go:171","msg":"trace[1198894937] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7vq9t; range_end:; response_count:1; response_revision:490; }","duration":"161.591239ms","start":"2026-02-04T01:55:53.714906Z","end":"2026-02-04T01:55:53.876497Z","steps":["trace[1198894937] 'agreement among raft nodes before linearized reading' (duration: 161.456875ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:56.035404Z","caller":"traceutil/trace.go:171","msg":"trace[816678314] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"117.110195ms","start":"2026-02-04T01:55:55.918279Z","end":"2026-02-04T01:55:56.035389Z","steps":["trace[816678314] 'process raft request' (duration: 117.038123ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:56.042069Z","caller":"traceutil/trace.go:171","msg":"trace[480023675] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"121.851976ms","start":"2026-02-04T01:55:55.920198Z","end":"2026-02-04T01:55:56.04205Z","steps":["trace[480023675] 'process raft request' (duration: 120.621565ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:56.044916Z","caller":"traceutil/trace.go:171","msg":"trace[1907179697] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"117.949757ms","start":"2026-02-04T01:55:55.926955Z","end":"2026-02-04T01:55:56.044905Z","steps":["trace[1907179697] 'process raft request' (duration: 117.922326ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:55:56.045144Z","caller":"traceutil/trace.go:171","msg":"trace[1942252925] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"118.217284ms","start":"2026-02-04T01:55:55.92692Z","end":"2026-02-04T01:55:56.045137Z","steps":["trace[1942252925] 'process raft request' (duration: 117.885016ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:13.704568Z","caller":"traceutil/trace.go:171","msg":"trace[1811180415] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"573.638912ms","start":"2026-02-04T01:56:13.130905Z","end":"2026-02-04T01:56:13.704544Z","steps":["trace[1811180415] 'process raft request' (duration: 573.518459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:13.705043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:13.130887Z","time spent":"573.778986ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T01:56:13.98688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.525648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T01:56:13.986972Z","caller":"traceutil/trace.go:171","msg":"trace[435994611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"130.649021ms","start":"2026-02-04T01:56:13.856305Z","end":"2026-02-04T01:56:13.986954Z","steps":["trace[435994611] 'range keys from in-memory index tree' (duration: 130.475466ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:14.093418Z","caller":"traceutil/trace.go:171","msg":"trace[1601040819] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"101.976599ms","start":"2026-02-04T01:56:13.991425Z","end":"2026-02-04T01:56:14.093401Z","steps":["trace[1601040819] 'process raft request' (duration: 101.544498ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:56:14.446191Z","caller":"traceutil/trace.go:171","msg":"trace[310794542] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"302.181333ms","start":"2026-02-04T01:56:14.143992Z","end":"2026-02-04T01:56:14.446174Z","steps":["trace[310794542] 'read index received' (duration: 235.00706ms)","trace[310794542] 'applied index is now lower than readState.Index' (duration: 67.173603ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:14.446333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.324736ms","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-04T01:56:14.446354Z","caller":"traceutil/trace.go:171","msg":"trace[1243997722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:684; }","duration":"302.370068ms","start":"2026-02-04T01:56:14.143977Z","end":"2026-02-04T01:56:14.446347Z","steps":["trace[1243997722] 'agreement among raft nodes before linearized reading' (duration: 302.258115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:56:14.446373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:14.14397Z","time spent":"302.398998ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-04T01:56:14.446531Z","caller":"traceutil/trace.go:171","msg":"trace[1497368677] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"305.533958ms","start":"2026-02-04T01:56:14.140989Z","end":"2026-02-04T01:56:14.446523Z","steps":["trace[1497368677] 'process raft request' (duration: 238.057019ms)","trace[1497368677] 'compare' (duration: 66.878814ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:56:14.446585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:56:14.140978Z","time spent":"305.570759ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:56:52.413526Z","caller":"traceutil/trace.go:171","msg":"trace[1420821156] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"119.556464ms","start":"2026-02-04T01:56:52.293955Z","end":"2026-02-04T01:56:52.413511Z","steps":["trace[1420821156] 'process raft request' (duration: 119.180935ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T01:57:04.874812Z","caller":"traceutil/trace.go:171","msg":"trace[1388611294] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"170.236068ms","start":"2026-02-04T01:57:04.70456Z","end":"2026-02-04T01:57:04.874796Z","steps":["trace[1388611294] 'process raft request' (duration: 80.129333ms)","trace[1388611294] 'compare' (duration: 89.836977ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:10.868678Z","caller":"traceutil/trace.go:171","msg":"trace[1456151752] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"187.597022ms","start":"2026-02-04T01:57:10.681064Z","end":"2026-02-04T01:57:10.868661Z","steps":["trace[1456151752] 'read index received' (duration: 120.222492ms)","trace[1456151752] 'applied index is now lower than readState.Index' (duration: 67.37387ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T01:57:10.868837Z","caller":"traceutil/trace.go:171","msg":"trace[1759180108] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"234.874237ms","start":"2026-02-04T01:57:10.633956Z","end":"2026-02-04T01:57:10.86883Z","steps":["trace[1759180108] 'process raft request' (duration: 167.374433ms)","trace[1759180108] 'compare' (duration: 67.259597ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:10.869006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.924651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-04T01:57:10.869024Z","caller":"traceutil/trace.go:171","msg":"trace[1490882651] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:920; }","duration":"187.983642ms","start":"2026-02-04T01:57:10.681034Z","end":"2026-02-04T01:57:10.869018Z","steps":["trace[1490882651] 'agreement among raft nodes before linearized reading' (duration: 187.89673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:10.869134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.918682ms","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-04T01:57:10.869146Z","caller":"traceutil/trace.go:171","msg":"trace[1835506612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:920; }","duration":"148.954762ms","start":"2026-02-04T01:57:10.720187Z","end":"2026-02-04T01:57:10.869142Z","steps":["trace[1835506612] 'agreement among raft nodes before linearized reading' (duration: 148.916701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.3111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.533045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"info","ts":"2026-02-04T01:57:21.311155Z","caller":"traceutil/trace.go:171","msg":"trace[1903096079] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:993; }","duration":"193.620707ms","start":"2026-02-04T01:57:21.117523Z","end":"2026-02-04T01:57:21.311144Z","steps":["trace[1903096079] 'range keys from in-memory index tree' (duration: 193.390242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.311377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.378146ms","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-04T01:57:21.311394Z","caller":"traceutil/trace.go:171","msg":"trace[1575907790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:993; }","duration":"293.430737ms","start":"2026-02-04T01:57:21.017959Z","end":"2026-02-04T01:57:21.31139Z","steps":["trace[1575907790] 'range keys from in-memory index tree' (duration: 293.330873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.311465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.42383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:503"} {"level":"info","ts":"2026-02-04T01:57:21.3116Z","caller":"traceutil/trace.go:171","msg":"trace[1435907602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"300.692367ms","start":"2026-02-04T01:57:21.010895Z","end":"2026-02-04T01:57:21.311587Z","steps":["trace[1435907602] 'range keys from in-memory index tree' (duration: 300.237474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.311779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:21.010873Z","time spent":"300.897292ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-04T01:57:21.714014Z","caller":"traceutil/trace.go:171","msg":"trace[518046880] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"395.744131ms","start":"2026-02-04T01:57:21.318248Z","end":"2026-02-04T01:57:21.713992Z","steps":["trace[518046880] 'process raft request' (duration: 395.69911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T01:57:21.714178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:21.318244Z","time spent":"395.839343ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T01:57:21.714481Z","caller":"traceutil/trace.go:171","msg":"trace[98628798] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"396.292674ms","start":"2026-02-04T01:57:21.318151Z","end":"2026-02-04T01:57:21.714444Z","steps":["trace[98628798] 'process raft request' (duration: 358.782284ms)","trace[98628798] 'compare' (duration: 36.920675ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T01:57:21.714745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T01:57:21.318144Z","time spent":"396.490731ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":456,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T02:00:28.480824Z","caller":"traceutil/trace.go:171","msg":"trace[168356044] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"105.30093ms","start":"2026-02-04T02:00:28.375497Z","end":"2026-02-04T02:00:28.480798Z","steps":["trace[168356044] 'process raft request' (duration: 105.127065ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T02:00:28.72628Z","caller":"traceutil/trace.go:171","msg":"trace[476074605] linearizableReadLoop","detail":"{readStateIndex:1872; appliedIndex:1871; }","duration":"125.926015ms","start":"2026-02-04T02:00:28.600332Z","end":"2026-02-04T02:00:28.726258Z","steps":["trace[476074605] 'read index received' (duration: 125.72135ms)","trace[476074605] 'applied index is now lower than readState.Index' (duration: 203.815µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T02:00:28.726532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.190892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2821"} {"level":"info","ts":"2026-02-04T02:00:28.726565Z","caller":"traceutil/trace.go:171","msg":"trace[2117626020] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1790; }","duration":"126.280264ms","start":"2026-02-04T02:00:28.600274Z","end":"2026-02-04T02:00:28.726554Z","steps":["trace[2117626020] 'agreement among raft nodes before linearized reading' (duration: 126.072229ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T02:00:28.726558Z","caller":"traceutil/trace.go:171","msg":"trace[1795216978] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"159.060784ms","start":"2026-02-04T02:00:28.567472Z","end":"2026-02-04T02:00:28.726533Z","steps":["trace[1795216978] 'process raft request' (duration: 158.642534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:46.323718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313659316702153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T02:00:46.82701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313659316702153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T02:00:47.327334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313659316702153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T02:00:47.828039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313659316702153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T02:00:47.852205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000096276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-04T02:00:47.852251Z","caller":"traceutil/trace.go:171","msg":"trace[2125887037] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000165448s","start":"2026-02-04T02:00:45.852067Z","end":"2026-02-04T02:00:47.852233Z","steps":["trace[2125887037] 'agreement among raft nodes before linearized reading' (duration: 2.000113957s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:47.852802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:45.852054Z","time spent":"2.000735122s","remote":"127.0.0.1:59272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/04 02:00:47 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-04T02:00:48.328378Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313659316702153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T02:00:48.829433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313659316702153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T02:00:49.329695Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313659316702153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T02:00:49.380785Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.57317195s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T02:00:49.381761Z","caller":"traceutil/trace.go:171","msg":"trace[1810784464] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"3.574185596s","start":"2026-02-04T02:00:45.807557Z","end":"2026-02-04T02:00:49.381742Z","steps":["trace[1810784464] 'process raft request' (duration: 3.573996931s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.381874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:45.807546Z","time spent":"3.574249398s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T02:00:49.382238Z","caller":"traceutil/trace.go:171","msg":"trace[827620187] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1953; }","duration":"3.559175708s","start":"2026-02-04T02:00:45.823053Z","end":"2026-02-04T02:00:49.382228Z","steps":["trace[827620187] 'read index received' (duration: 3.558625693s)","trace[827620187] 'applied index is now lower than readState.Index' (duration: 549.085µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T02:00:49.382286Z","caller":"traceutil/trace.go:171","msg":"trace[1730967069] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"3.289856194s","start":"2026-02-04T02:00:46.092424Z","end":"2026-02-04T02:00:49.38228Z","steps":["trace[1730967069] 'process raft request' (duration: 3.289739241s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.382337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:46.092401Z","time spent":"3.289912267s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T02:00:49.382455Z","caller":"traceutil/trace.go:171","msg":"trace[1153322513] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"342.349156ms","start":"2026-02-04T02:00:49.040098Z","end":"2026-02-04T02:00:49.382447Z","steps":["trace[1153322513] 'process raft request' (duration: 342.1088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.382485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:49.040083Z","time spent":"342.385307ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T02:00:49.382612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.559559957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:1 size:655"} {"level":"info","ts":"2026-02-04T02:00:49.382641Z","caller":"traceutil/trace.go:171","msg":"trace[694212481] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:1; response_revision:1868; }","duration":"3.559601308s","start":"2026-02-04T02:00:45.823033Z","end":"2026-02-04T02:00:49.382634Z","steps":["trace[694212481] 'agreement among raft nodes before linearized reading' (duration: 3.559514456s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.382662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:45.823012Z","time spent":"3.559643939s","remote":"127.0.0.1:59596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":679,"request content":"key:\"/registry/clusterroles/system:node-problem-detector\" "} {"level":"warn","ts":"2026-02-04T02:00:49.383491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.513091352s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-04T02:00:49.383519Z","caller":"traceutil/trace.go:171","msg":"trace[496894611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1868; }","duration":"3.513157004s","start":"2026-02-04T02:00:45.870354Z","end":"2026-02-04T02:00:49.383511Z","steps":["trace[496894611] 'agreement among raft nodes before linearized reading' (duration: 3.513067762s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.383539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:45.870338Z","time spent":"3.513196495s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-04T02:00:49.383641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.60252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T02:00:49.383661Z","caller":"traceutil/trace.go:171","msg":"trace[1428109491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1868; }","duration":"797.681842ms","start":"2026-02-04T02:00:48.585974Z","end":"2026-02-04T02:00:49.383656Z","steps":["trace[1428109491] 'agreement among raft nodes before linearized reading' (duration: 797.650131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.383676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:48.585963Z","time spent":"797.710422ms","remote":"127.0.0.1:59270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T02:00:49.383932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.070167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-04T02:00:49.383957Z","caller":"traceutil/trace.go:171","msg":"trace[1234608027] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1868; }","duration":"875.124339ms","start":"2026-02-04T02:00:48.508827Z","end":"2026-02-04T02:00:49.383951Z","steps":["trace[1234608027] 'agreement among raft nodes before linearized reading' (duration: 874.934764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.383991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:48.508812Z","time spent":"875.17386ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-04T02:00:49.384077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633704046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-04T02:00:49.384098Z","caller":"traceutil/trace.go:171","msg":"trace[308756749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1868; }","duration":"1.633748617s","start":"2026-02-04T02:00:47.750342Z","end":"2026-02-04T02:00:49.38409Z","steps":["trace[308756749] 'agreement among raft nodes before linearized reading' (duration: 1.633695706s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.384113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:47.75033Z","time spent":"1.633779668s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T02:00:49.384189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.180664568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-04T02:00:49.384207Z","caller":"traceutil/trace.go:171","msg":"trace[1191819353] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1868; }","duration":"2.18075535s","start":"2026-02-04T02:00:47.203447Z","end":"2026-02-04T02:00:49.384202Z","steps":["trace[1191819353] 'agreement among raft nodes before linearized reading' (duration: 2.180703889s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.384223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:47.203435Z","time spent":"2.180783931s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-04T02:00:49.384293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.318732637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"info","ts":"2026-02-04T02:00:49.384312Z","caller":"traceutil/trace.go:171","msg":"trace[131084329] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1868; }","duration":"2.31880857s","start":"2026-02-04T02:00:47.065498Z","end":"2026-02-04T02:00:49.384306Z","steps":["trace[131084329] 'agreement among raft nodes before linearized reading' (duration: 2.318762178s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.384327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:47.065455Z","time spent":"2.318868271s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-04T02:00:49.384392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.758494669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-04T02:00:49.38441Z","caller":"traceutil/trace.go:171","msg":"trace[16102725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1868; }","duration":"2.758556912s","start":"2026-02-04T02:00:46.625848Z","end":"2026-02-04T02:00:49.384405Z","steps":["trace[16102725] 'agreement among raft nodes before linearized reading' (duration: 2.758511421s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.384425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:46.625828Z","time spent":"2.758591822s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T02:00:49.384489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.206070203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-04T02:00:49.384507Z","caller":"traceutil/trace.go:171","msg":"trace[719159400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1868; }","duration":"3.206115084s","start":"2026-02-04T02:00:46.178388Z","end":"2026-02-04T02:00:49.384503Z","steps":["trace[719159400] 'agreement among raft nodes before linearized reading' (duration: 3.206069763s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.384523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:46.178376Z","time spent":"3.206142025s","remote":"127.0.0.1:59530","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-04T02:00:49.384591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.43975416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-04T02:00:49.384609Z","caller":"traceutil/trace.go:171","msg":"trace[947609232] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1868; }","duration":"3.439792632s","start":"2026-02-04T02:00:45.944812Z","end":"2026-02-04T02:00:49.384605Z","steps":["trace[947609232] 'agreement among raft nodes before linearized reading' (duration: 3.439749301s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.384622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:45.944802Z","time spent":"3.439817122s","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-04T02:00:49.384669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.50491242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:1 size:633"} {"level":"info","ts":"2026-02-04T02:00:49.384687Z","caller":"traceutil/trace.go:171","msg":"trace[940737915] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:1; response_revision:1868; }","duration":"3.504940771s","start":"2026-02-04T02:00:45.879742Z","end":"2026-02-04T02:00:49.384683Z","steps":["trace[940737915] 'agreement among raft nodes before linearized reading' (duration: 3.50491085s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T02:00:49.384701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T02:00:45.879737Z","time spent":"3.504961501s","remote":"127.0.0.1:59596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":657,"request content":"key:\"/registry/clusterroles/system:node-bootstrapper\" "}