{"level":"warn","ts":"2026-03-19T21:46:05.693177Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T21:46:05.693275Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.236: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.19.213.236:2380","--initial-cluster=instance=https://199.19.213.236:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.236:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.236: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-03-19T21:46:05.693353Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T21:46:05.693366Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.236:2380"]} {"level":"info","ts":"2026-03-19T21:46:05.693385Z","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-03-19T21:46:05.694071Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"]} {"level":"info","ts":"2026-03-19T21:46:05.694147Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.236:2380"],"listen-peer-urls":["https://199.19.213.236:2380"],"advertise-client-urls":["https://199.19.213.236:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.236: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-03-19T21:46:05.699154Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.810496ms"} {"level":"info","ts":"2026-03-19T21:46:05.706449Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f20dfa43a2e4607","cluster-id":"85a3671ea9bffea4"} {"level":"info","ts":"2026-03-19T21:46:05.706559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T21:46:05.706588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became follower at term 0"} {"level":"info","ts":"2026-03-19T21:46:05.706603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f20dfa43a2e4607 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T21:46:05.706612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became follower at term 1"} {"level":"info","ts":"2026-03-19T21:46:05.706663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=(6854724529301636615)"} {"level":"warn","ts":"2026-03-19T21:46:05.714457Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T21:46:05.716163Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T21:46:05.719273Z","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-03-19T21:46:05.725256Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f20dfa43a2e4607","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T21:46:05.725395Z","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-03-19T21:46:05.725498Z","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-03-19T21:46:05.725508Z","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-03-19T21:46:05.725489Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f20dfa43a2e4607","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T21:46:05.726218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=(6854724529301636615)"} {"level":"info","ts":"2026-03-19T21:46:05.72636Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"85a3671ea9bffea4","local-member-id":"5f20dfa43a2e4607","added-peer-id":"5f20dfa43a2e4607","added-peer-peer-urls":["https://199.19.213.236:2380"]} {"level":"info","ts":"2026-03-19T21:46:05.731801Z","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-03-19T21:46:05.731977Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.236:2380"} {"level":"info","ts":"2026-03-19T21:46:05.732042Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.236:2380"} {"level":"info","ts":"2026-03-19T21:46:05.732562Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f20dfa43a2e4607","initial-advertise-peer-urls":["https://199.19.213.236:2380"],"listen-peer-urls":["https://199.19.213.236:2380"],"advertise-client-urls":["https://199.19.213.236:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T21:46:05.732602Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T21:46:06.507749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T21:46:06.507869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T21:46:06.507945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 received MsgPreVoteResp from 5f20dfa43a2e4607 at term 1"} {"level":"info","ts":"2026-03-19T21:46:06.508024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became candidate at term 2"} {"level":"info","ts":"2026-03-19T21:46:06.508042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 received MsgVoteResp from 5f20dfa43a2e4607 at term 2"} {"level":"info","ts":"2026-03-19T21:46:06.508066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became leader at term 2"} {"level":"info","ts":"2026-03-19T21:46:06.508127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f20dfa43a2e4607 elected leader 5f20dfa43a2e4607 at term 2"} {"level":"info","ts":"2026-03-19T21:46:06.510139Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:46:06.511566Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T21:46:06.511561Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f20dfa43a2e4607","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.236:2379]}","request-path":"/0/members/5f20dfa43a2e4607/attributes","cluster-id":"85a3671ea9bffea4","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T21:46:06.511638Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T21:46:06.51211Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T21:46:06.512146Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T21:46:06.512452Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"85a3671ea9bffea4","local-member-id":"5f20dfa43a2e4607","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:46:06.512664Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:46:06.512777Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T21:46:06.515499Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.236:2379"} {"level":"info","ts":"2026-03-19T21:46:06.51562Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-19T21:46:13.633421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.746615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:46:13.633559Z","caller":"traceutil/trace.go:171","msg":"trace[2066789605] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:285; }","duration":"261.964225ms","start":"2026-03-19T21:46:13.371564Z","end":"2026-03-19T21:46:13.633529Z","steps":["trace[2066789605] 'range keys from in-memory index tree' (duration: 261.674915ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:46:15.13139Z","caller":"traceutil/trace.go:171","msg":"trace[1172884656] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:312; }","duration":"158.444003ms","start":"2026-03-19T21:46:14.972913Z","end":"2026-03-19T21:46:15.131357Z","steps":["trace[1172884656] 'read index received' (duration: 158.197932ms)","trace[1172884656] 'applied index is now lower than readState.Index' (duration: 244.291µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:46:15.131481Z","caller":"traceutil/trace.go:171","msg":"trace[124966033] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"159.723276ms","start":"2026-03-19T21:46:14.97172Z","end":"2026-03-19T21:46:15.131443Z","steps":["trace[124966033] 'process raft request' (duration: 159.475485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:15.132617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.671986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-19T21:46:15.132896Z","caller":"traceutil/trace.go:171","msg":"trace[1750514798] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:298; }","duration":"159.930657ms","start":"2026-03-19T21:46:14.972857Z","end":"2026-03-19T21:46:15.132765Z","steps":["trace[1750514798] 'agreement among raft nodes before linearized reading' (duration: 158.805863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:15.136296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.282638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-19T21:46:15.137013Z","caller":"traceutil/trace.go:171","msg":"trace[761680050] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:298; }","duration":"114.590292ms","start":"2026-03-19T21:46:15.022392Z","end":"2026-03-19T21:46:15.136982Z","steps":["trace[761680050] 'agreement among raft nodes before linearized reading' (duration: 109.307516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:15.290132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.504361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-19T21:46:15.290227Z","caller":"traceutil/trace.go:171","msg":"trace[1870458243] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:298; }","duration":"117.718082ms","start":"2026-03-19T21:46:15.172488Z","end":"2026-03-19T21:46:15.290207Z","steps":["trace[1870458243] 'range keys from in-memory index tree' (duration: 117.260311ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:46:16.743427Z","caller":"traceutil/trace.go:171","msg":"trace[1930995105] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"129.246297ms","start":"2026-03-19T21:46:16.614146Z","end":"2026-03-19T21:46:16.743392Z","steps":["trace[1930995105] 'process raft request' (duration: 129.032617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:21.038565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.485048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-19T21:46:21.038654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.886631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-03-19T21:46:21.038716Z","caller":"traceutil/trace.go:171","msg":"trace[89850984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:362; }","duration":"199.75239ms","start":"2026-03-19T21:46:20.838933Z","end":"2026-03-19T21:46:21.038686Z","steps":["trace[89850984] 'range keys from in-memory index tree' (duration: 199.303808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:21.038591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.978811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:46:21.038803Z","caller":"traceutil/trace.go:171","msg":"trace[1491468703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:362; }","duration":"523.292003ms","start":"2026-03-19T21:46:20.515492Z","end":"2026-03-19T21:46:21.038784Z","steps":["trace[1491468703] 'range keys from in-memory index tree' (duration: 522.807971ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:21.038882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:20.515462Z","time spent":"523.408463ms","remote":"127.0.0.1:48592","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-03-19T21:46:21.038753Z","caller":"traceutil/trace.go:171","msg":"trace[12016315] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:362; }","duration":"478.020043ms","start":"2026-03-19T21:46:20.560702Z","end":"2026-03-19T21:46:21.038722Z","steps":["trace[12016315] 'range keys from in-memory index tree' (duration: 477.745391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:21.039126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:20.560677Z","time spent":"478.430614ms","remote":"127.0.0.1:48758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-03-19T21:46:21.665451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5046174565447241048,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T21:46:21.703846Z","caller":"traceutil/trace.go:171","msg":"trace[1925141875] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"659.726579ms","start":"2026-03-19T21:46:21.044091Z","end":"2026-03-19T21:46:21.703817Z","steps":["trace[1925141875] 'process raft request' (duration: 659.384628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:21.705263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:21.044057Z","time spent":"659.875029ms","remote":"127.0.0.1:48836","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-03-19T21:46:23.088578Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.373297963s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T21:46:23.089211Z","caller":"traceutil/trace.go:171","msg":"trace[820375837] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"2.040327894s","start":"2026-03-19T21:46:21.048853Z","end":"2026-03-19T21:46:23.089181Z","steps":["trace[820375837] 'process raft request' (duration: 2.039944325s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:23.089433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:21.048833Z","time spent":"2.040431168s","remote":"127.0.0.1:48758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:46:23.089719Z","caller":"traceutil/trace.go:171","msg":"trace[192870995] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:379; }","duration":"1.92528914s","start":"2026-03-19T21:46:21.164364Z","end":"2026-03-19T21:46:23.089653Z","steps":["trace[192870995] 'read index received' (duration: 539.204717ms)","trace[192870995] 'applied index is now lower than readState.Index' (duration: 1.386080953s)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:46:23.089778Z","caller":"traceutil/trace.go:171","msg":"trace[1527924663] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"1.494787102s","start":"2026-03-19T21:46:21.594938Z","end":"2026-03-19T21:46:23.089725Z","steps":["trace[1527924663] 'process raft request' (duration: 1.494182563s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:23.090034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925640216s","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-03-19T21:46:23.090095Z","caller":"traceutil/trace.go:171","msg":"trace[1262206750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:365; }","duration":"1.925777061s","start":"2026-03-19T21:46:21.164304Z","end":"2026-03-19T21:46:23.090081Z","steps":["trace[1262206750] 'agreement among raft nodes before linearized reading' (duration: 1.925594422s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:23.090137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:21.164278Z","time spent":"1.925851263s","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T21:46:23.090108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:21.594902Z","time spent":"1.495079125s","remote":"127.0.0.1:48836","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-03-19T21:46:23.090334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.972145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-19T21:46:23.090386Z","caller":"traceutil/trace.go:171","msg":"trace[1905669619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:365; }","duration":"823.088927ms","start":"2026-03-19T21:46:22.267277Z","end":"2026-03-19T21:46:23.090366Z","steps":["trace[1905669619] 'agreement among raft nodes before linearized reading' (duration: 822.999573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:23.090429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:22.267256Z","time spent":"823.16602ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T21:46:23.09042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.56197756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:46:23.090518Z","caller":"traceutil/trace.go:171","msg":"trace[856778990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:365; }","duration":"1.562115094s","start":"2026-03-19T21:46:21.528347Z","end":"2026-03-19T21:46:23.090462Z","steps":["trace[856778990] 'agreement among raft nodes before linearized reading' (duration: 1.56183225s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:23.090594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:21.528319Z","time spent":"1.562236228s","remote":"127.0.0.1:48592","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-03-19T21:46:23.091227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086229454s","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-03-19T21:46:23.091364Z","caller":"traceutil/trace.go:171","msg":"trace[1773889523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:365; }","duration":"1.086412883s","start":"2026-03-19T21:46:22.004938Z","end":"2026-03-19T21:46:23.091351Z","steps":["trace[1773889523] 'agreement among raft nodes before linearized reading' (duration: 1.085342551s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:23.091546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:22.004863Z","time spent":"1.086671452s","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T21:46:23.870764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.605511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:46:23.871003Z","caller":"traceutil/trace.go:171","msg":"trace[1310533663] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:382; }","duration":"774.620418ms","start":"2026-03-19T21:46:23.096364Z","end":"2026-03-19T21:46:23.870984Z","steps":["trace[1310533663] 'read index received' (duration: 33.492518ms)","trace[1310533663] 'applied index is now lower than readState.Index' (duration: 741.12717ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:46:23.871111Z","caller":"traceutil/trace.go:171","msg":"trace[1560703199] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"776.046107ms","start":"2026-03-19T21:46:23.095052Z","end":"2026-03-19T21:46:23.871098Z","steps":["trace[1560703199] 'process raft request' (duration: 663.823714ms)","trace[1560703199] 'compare' (duration: 111.068736ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:46:23.871176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:23.095018Z","time spent":"776.12797ms","remote":"127.0.0.1:48836","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-03-19T21:46:23.871432Z","caller":"traceutil/trace.go:171","msg":"trace[1790320802] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"775.545212ms","start":"2026-03-19T21:46:23.095877Z","end":"2026-03-19T21:46:23.871422Z","steps":["trace[1790320802] 'process raft request' (duration: 775.003816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:23.871483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:23.095866Z","time spent":"775.588815ms","remote":"127.0.0.1:48836","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-03-19T21:46:23.871743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.453862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:46:23.871797Z","caller":"traceutil/trace.go:171","msg":"trace[194731407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:368; }","duration":"772.551327ms","start":"2026-03-19T21:46:23.099236Z","end":"2026-03-19T21:46:23.871787Z","steps":["trace[194731407] 'agreement among raft nodes before linearized reading' (duration: 772.469893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:23.871728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.336132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"warn","ts":"2026-03-19T21:46:23.871869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.114547ms","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-03-19T21:46:23.871876Z","caller":"traceutil/trace.go:171","msg":"trace[883339568] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:368; }","duration":"775.555213ms","start":"2026-03-19T21:46:23.096291Z","end":"2026-03-19T21:46:23.871846Z","steps":["trace[883339568] 'agreement among raft nodes before linearized reading' (duration: 775.282649ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:46:23.871902Z","caller":"traceutil/trace.go:171","msg":"trace[427977713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:368; }","duration":"162.194951ms","start":"2026-03-19T21:46:23.7097Z","end":"2026-03-19T21:46:23.871895Z","steps":["trace[427977713] 'agreement among raft nodes before linearized reading' (duration: 162.138798ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:23.871949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:23.096268Z","time spent":"775.665758ms","remote":"127.0.0.1:48758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5359,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-03-19T21:46:23.871823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:23.099221Z","time spent":"772.596389ms","remote":"127.0.0.1:48592","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-03-19T21:46:24.29464Z","caller":"traceutil/trace.go:171","msg":"trace[1905405657] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"417.70454ms","start":"2026-03-19T21:46:23.87689Z","end":"2026-03-19T21:46:24.294595Z","steps":["trace[1905405657] 'process raft request' (duration: 329.023147ms)","trace[1905405657] 'compare' (duration: 88.292164ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:46:24.294851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:23.876857Z","time spent":"417.883719ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:46:24.320286Z","caller":"traceutil/trace.go:171","msg":"trace[540423253] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"441.449348ms","start":"2026-03-19T21:46:23.878806Z","end":"2026-03-19T21:46:24.320256Z","steps":["trace[540423253] 'process raft request' (duration: 441.26868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:24.320449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:23.878787Z","time spent":"441.577724ms","remote":"127.0.0.1:48758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:46:36.366006Z","caller":"traceutil/trace.go:171","msg":"trace[978335954] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"331.314068ms","start":"2026-03-19T21:46:36.034671Z","end":"2026-03-19T21:46:36.365985Z","steps":["trace[978335954] 'process raft request' (duration: 331.164432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:36.366156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:36.034649Z","time spent":"331.426313ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:46:38.838618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.994181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:46:38.838866Z","caller":"traceutil/trace.go:171","msg":"trace[637687411] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"230.876368ms","start":"2026-03-19T21:46:38.607944Z","end":"2026-03-19T21:46:38.83882Z","steps":["trace[637687411] 'process raft request' (duration: 128.592016ms)","trace[637687411] 'compare' (duration: 101.831213ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:46:38.839021Z","caller":"traceutil/trace.go:171","msg":"trace[1716542184] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"112.348875ms","start":"2026-03-19T21:46:38.72665Z","end":"2026-03-19T21:46:38.838999Z","steps":["trace[1716542184] 'process raft request' (duration: 112.088505ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:46:39.179637Z","caller":"traceutil/trace.go:171","msg":"trace[286464863] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"295.478925ms","start":"2026-03-19T21:46:38.884129Z","end":"2026-03-19T21:46:39.179608Z","steps":["trace[286464863] 'process raft request' (duration: 247.898565ms)","trace[286464863] 'compare' (duration: 47.040159ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:46:39.989157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.341752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-19T21:46:39.989282Z","caller":"traceutil/trace.go:171","msg":"trace[2129994389] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:471; }","duration":"206.53604ms","start":"2026-03-19T21:46:39.782721Z","end":"2026-03-19T21:46:39.989257Z","steps":["trace[2129994389] 'range keys from in-memory index tree' (duration: 206.151835ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:46:40.281893Z","caller":"traceutil/trace.go:171","msg":"trace[1540880476] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"194.708127ms","start":"2026-03-19T21:46:40.087144Z","end":"2026-03-19T21:46:40.281852Z","steps":["trace[1540880476] 'process raft request' (duration: 108.208832ms)","trace[1540880476] 'compare' (duration: 86.37003ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:46:40.504838Z","caller":"traceutil/trace.go:171","msg":"trace[1425343879] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"216.05698ms","start":"2026-03-19T21:46:40.288726Z","end":"2026-03-19T21:46:40.504783Z","steps":["trace[1425343879] 'process raft request' (duration: 129.074125ms)","trace[1425343879] 'compare' (duration: 86.771896ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:46:40.763667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.852914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T21:46:40.763952Z","caller":"traceutil/trace.go:171","msg":"trace[280548876] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:497; }","duration":"249.062687ms","start":"2026-03-19T21:46:40.514868Z","end":"2026-03-19T21:46:40.763931Z","steps":["trace[280548876] 'read index received' (duration: 104.919893ms)","trace[280548876] 'applied index is now lower than readState.Index' (duration: 144.141254ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:46:40.764021Z","caller":"traceutil/trace.go:171","msg":"trace[1302410764] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"254.640805ms","start":"2026-03-19T21:46:40.509342Z","end":"2026-03-19T21:46:40.763983Z","steps":["trace[1302410764] 'process raft request' (duration: 110.359675ms)","trace[1302410764] 'compare' (duration: 143.673386ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:46:40.764041Z","caller":"traceutil/trace.go:171","msg":"trace[2088159708] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"254.135136ms","start":"2026-03-19T21:46:40.50985Z","end":"2026-03-19T21:46:40.763985Z","steps":["trace[2088159708] 'process raft request' (duration: 254.015431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:40.764142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.237834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:46:40.764228Z","caller":"traceutil/trace.go:171","msg":"trace[1941885120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"249.345348ms","start":"2026-03-19T21:46:40.514861Z","end":"2026-03-19T21:46:40.764206Z","steps":["trace[1941885120] 'agreement among raft nodes before linearized reading' (duration: 249.148031ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:46:41.788801Z","caller":"traceutil/trace.go:171","msg":"trace[749029358] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:500; }","duration":"272.915275ms","start":"2026-03-19T21:46:41.515845Z","end":"2026-03-19T21:46:41.78876Z","steps":["trace[749029358] 'read index received' (duration: 264.857915ms)","trace[749029358] 'applied index is now lower than readState.Index' (duration: 8.05611ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:46:41.788934Z","caller":"traceutil/trace.go:171","msg":"trace[1492574752] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"380.061203ms","start":"2026-03-19T21:46:41.40884Z","end":"2026-03-19T21:46:41.788901Z","steps":["trace[1492574752] 'process raft request' (duration: 371.802455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:41.789014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.138064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T21:46:41.789131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:41.40881Z","time spent":"380.185178ms","remote":"127.0.0.1:48748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:46:41.789144Z","caller":"traceutil/trace.go:171","msg":"trace[975971453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:478; }","duration":"273.351932ms","start":"2026-03-19T21:46:41.51575Z","end":"2026-03-19T21:46:41.789102Z","steps":["trace[975971453] 'agreement among raft nodes before linearized reading' (duration: 273.150514ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:46:43.84637Z","caller":"traceutil/trace.go:171","msg":"trace[745627498] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"333.085577ms","start":"2026-03-19T21:46:43.51318Z","end":"2026-03-19T21:46:43.846266Z","steps":["trace[745627498] 'read index received' (duration: 333.009214ms)","trace[745627498] 'applied index is now lower than readState.Index' (duration: 75.133µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:46:43.846405Z","caller":"traceutil/trace.go:171","msg":"trace[216403677] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"438.208817ms","start":"2026-03-19T21:46:43.408133Z","end":"2026-03-19T21:46:43.846341Z","steps":["trace[216403677] 'process raft request' (duration: 437.974088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:43.846562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.350997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:46:43.846662Z","caller":"traceutil/trace.go:171","msg":"trace[1713763160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"333.507443ms","start":"2026-03-19T21:46:43.513133Z","end":"2026-03-19T21:46:43.846641Z","steps":["trace[1713763160] 'agreement among raft nodes before linearized reading' (duration: 333.319336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:43.84665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:43.408104Z","time spent":"438.424176ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:46:43.846699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:43.51311Z","time spent":"333.584016ms","remote":"127.0.0.1:48592","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-03-19T21:46:44.077783Z","caller":"traceutil/trace.go:171","msg":"trace[1607005976] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:505; }","duration":"229.553851ms","start":"2026-03-19T21:46:43.848195Z","end":"2026-03-19T21:46:44.077749Z","steps":["trace[1607005976] 'read index received' (duration: 146.955261ms)","trace[1607005976] 'applied index is now lower than readState.Index' (duration: 82.59699ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:46:44.077972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.774449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:46:44.078009Z","caller":"traceutil/trace.go:171","msg":"trace[179019934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"229.873793ms","start":"2026-03-19T21:46:43.848122Z","end":"2026-03-19T21:46:44.077996Z","steps":["trace[179019934] 'agreement among raft nodes before linearized reading' (duration: 229.77537ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:46:44.077989Z","caller":"traceutil/trace.go:171","msg":"trace[193764643] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"456.939346ms","start":"2026-03-19T21:46:43.621006Z","end":"2026-03-19T21:46:44.077945Z","steps":["trace[193764643] 'process raft request' (duration: 374.148459ms)","trace[193764643] 'compare' (duration: 82.329691ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:46:44.079079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:43.62097Z","time spent":"457.791198ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:46:45.328853Z","caller":"traceutil/trace.go:171","msg":"trace[801383904] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"463.390279ms","start":"2026-03-19T21:46:44.865428Z","end":"2026-03-19T21:46:45.328818Z","steps":["trace[801383904] 'read index received' (duration: 463.168841ms)","trace[801383904] 'applied index is now lower than readState.Index' (duration: 219.698µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T21:46:45.329066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.507792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:46:45.329122Z","caller":"traceutil/trace.go:171","msg":"trace[1474012758] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:485; }","duration":"277.629047ms","start":"2026-03-19T21:46:45.051473Z","end":"2026-03-19T21:46:45.329103Z","steps":["trace[1474012758] 'agreement among raft nodes before linearized reading' (duration: 277.516773ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:46:45.329078Z","caller":"traceutil/trace.go:171","msg":"trace[977852602] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"464.305603ms","start":"2026-03-19T21:46:44.864621Z","end":"2026-03-19T21:46:45.328927Z","steps":["trace[977852602] 'process raft request' (duration: 463.889398ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:45.329165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.730691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T21:46:45.329332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:44.864602Z","time spent":"464.583092ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:46:45.329235Z","caller":"traceutil/trace.go:171","msg":"trace[6693412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:485; }","duration":"463.818054ms","start":"2026-03-19T21:46:44.865403Z","end":"2026-03-19T21:46:45.329221Z","steps":["trace[6693412] 'agreement among raft nodes before linearized reading' (duration: 463.570465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:45.329579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:44.865397Z","time spent":"464.162247ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T21:46:51.298368Z","caller":"traceutil/trace.go:171","msg":"trace[809298365] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"574.846624ms","start":"2026-03-19T21:46:50.72349Z","end":"2026-03-19T21:46:51.298336Z","steps":["trace[809298365] 'process raft request' (duration: 574.682169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:51.298556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:50.723446Z","time spent":"575.0167ms","remote":"127.0.0.1:48758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T21:46:52.279817Z","caller":"traceutil/trace.go:171","msg":"trace[1316241863] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"393.772809ms","start":"2026-03-19T21:46:51.885972Z","end":"2026-03-19T21:46:52.279745Z","steps":["trace[1316241863] 'process raft request' (duration: 393.5314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:52.280079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:51.885957Z","time spent":"393.979795ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T21:46:53.875942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.780424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:46:53.876036Z","caller":"traceutil/trace.go:171","msg":"trace[1462590543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"360.9724ms","start":"2026-03-19T21:46:53.515044Z","end":"2026-03-19T21:46:53.876016Z","steps":["trace[1462590543] 'range keys from in-memory index tree' (duration: 360.69346ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:53.876071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:53.515019Z","time spent":"361.044842ms","remote":"127.0.0.1:48592","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-03-19T21:46:53.876134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434004448s","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-03-19T21:46:53.876204Z","caller":"traceutil/trace.go:171","msg":"trace[81007821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"1.434141693s","start":"2026-03-19T21:46:52.442045Z","end":"2026-03-19T21:46:53.876186Z","steps":["trace[81007821] 'range keys from in-memory index tree' (duration: 1.433754228s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:53.876171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.996038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-19T21:46:53.87624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:52.442005Z","time spent":"1.434228626s","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T21:46:53.876238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.712148ms","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-03-19T21:46:53.876277Z","caller":"traceutil/trace.go:171","msg":"trace[315458021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"502.173594ms","start":"2026-03-19T21:46:53.374076Z","end":"2026-03-19T21:46:53.87625Z","steps":["trace[315458021] 'range keys from in-memory index tree' (duration: 501.655107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:53.876345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:53.37405Z","time spent":"502.283577ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-19T21:46:53.876342Z","caller":"traceutil/trace.go:171","msg":"trace[1879861257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"461.860083ms","start":"2026-03-19T21:46:53.414453Z","end":"2026-03-19T21:46:53.876313Z","steps":["trace[1879861257] 'range keys from in-memory index tree' (duration: 461.420728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:53.876395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T21:46:53.414437Z","time spent":"461.948006ms","remote":"127.0.0.1:48836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T21:46:56.080034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.329797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T21:46:56.080153Z","caller":"traceutil/trace.go:171","msg":"trace[1426609591] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:560; }","duration":"202.490772ms","start":"2026-03-19T21:46:55.877632Z","end":"2026-03-19T21:46:56.080122Z","steps":["trace[1426609591] 'range keys from in-memory index tree' (duration: 202.259795ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:56.080124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.665116ms","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-03-19T21:46:56.080257Z","caller":"traceutil/trace.go:171","msg":"trace[852131078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"128.853332ms","start":"2026-03-19T21:46:55.951375Z","end":"2026-03-19T21:46:56.080228Z","steps":["trace[852131078] 'range keys from in-memory index tree' (duration: 128.48018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:46:56.080284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.456877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T21:46:56.080285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.651086ms","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-03-19T21:46:56.080345Z","caller":"traceutil/trace.go:171","msg":"trace[1045173354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"127.553829ms","start":"2026-03-19T21:46:55.952772Z","end":"2026-03-19T21:46:56.080326Z","steps":["trace[1045173354] 'range keys from in-memory index tree' (duration: 127.294282ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:46:56.080357Z","caller":"traceutil/trace.go:171","msg":"trace[1752505193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"148.759899ms","start":"2026-03-19T21:46:55.931576Z","end":"2026-03-19T21:46:56.080336Z","steps":["trace[1752505193] 'range keys from in-memory index tree' (duration: 148.470249ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:48:18.686338Z","caller":"traceutil/trace.go:171","msg":"trace[992972315] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"104.276242ms","start":"2026-03-19T21:48:18.582021Z","end":"2026-03-19T21:48:18.686297Z","steps":["trace[992972315] 'process raft request' (duration: 103.897676ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:48:18.807725Z","caller":"traceutil/trace.go:171","msg":"trace[1386761097] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"114.628302ms","start":"2026-03-19T21:48:18.693066Z","end":"2026-03-19T21:48:18.807694Z","steps":["trace[1386761097] 'process raft request' (duration: 92.645364ms)","trace[1386761097] 'compare' (duration: 21.806536ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:48:18.808223Z","caller":"traceutil/trace.go:171","msg":"trace[654562811] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"115.073648ms","start":"2026-03-19T21:48:18.693132Z","end":"2026-03-19T21:48:18.808205Z","steps":["trace[654562811] 'process raft request' (duration: 114.515921ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:48:18.808965Z","caller":"traceutil/trace.go:171","msg":"trace[997785328] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"113.024399ms","start":"2026-03-19T21:48:18.695772Z","end":"2026-03-19T21:48:18.808797Z","steps":["trace[997785328] 'process raft request' (duration: 112.905407ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:48:20.932885Z","caller":"traceutil/trace.go:171","msg":"trace[458310023] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"119.405617ms","start":"2026-03-19T21:48:20.813448Z","end":"2026-03-19T21:48:20.932854Z","steps":["trace[458310023] 'read index received' (duration: 119.177974ms)","trace[458310023] 'applied index is now lower than readState.Index' (duration: 226.503µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:48:20.933137Z","caller":"traceutil/trace.go:171","msg":"trace[1808029331] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"213.522091ms","start":"2026-03-19T21:48:20.719495Z","end":"2026-03-19T21:48:20.933017Z","steps":["trace[1808029331] 'process raft request' (duration: 213.189917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:48:20.933217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.736392ms","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-03-19T21:48:20.933277Z","caller":"traceutil/trace.go:171","msg":"trace[1719656215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"119.830953ms","start":"2026-03-19T21:48:20.813429Z","end":"2026-03-19T21:48:20.93326Z","steps":["trace[1719656215] 'agreement among raft nodes before linearized reading' (duration: 119.595969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:48:20.933314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.62284ms","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-03-19T21:48:20.933457Z","caller":"traceutil/trace.go:171","msg":"trace[956124371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"119.778122ms","start":"2026-03-19T21:48:20.813655Z","end":"2026-03-19T21:48:20.933433Z","steps":["trace[956124371] 'agreement among raft nodes before linearized reading' (duration: 119.518838ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:48:29.158736Z","caller":"traceutil/trace.go:171","msg":"trace[1823949574] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"170.389591ms","start":"2026-03-19T21:48:28.988315Z","end":"2026-03-19T21:48:29.158705Z","steps":["trace[1823949574] 'process raft request' (duration: 170.205459ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:48:29.182731Z","caller":"traceutil/trace.go:171","msg":"trace[1208387029] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1031; }","duration":"193.938877ms","start":"2026-03-19T21:48:28.988764Z","end":"2026-03-19T21:48:29.182703Z","steps":["trace[1208387029] 'read index received' (duration: 169.752471ms)","trace[1208387029] 'applied index is now lower than readState.Index' (duration: 24.185776ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T21:48:29.182828Z","caller":"traceutil/trace.go:171","msg":"trace[1976929005] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"193.565422ms","start":"2026-03-19T21:48:28.989238Z","end":"2026-03-19T21:48:29.182803Z","steps":["trace[1976929005] 'process raft request' (duration: 193.441001ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T21:48:29.182823Z","caller":"traceutil/trace.go:171","msg":"trace[1988377697] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"194.037099ms","start":"2026-03-19T21:48:28.98873Z","end":"2026-03-19T21:48:29.182767Z","steps":["trace[1988377697] 'process raft request' (duration: 193.818476ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T21:48:29.182923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.14008ms","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-03-19T21:48:29.182949Z","caller":"traceutil/trace.go:171","msg":"trace[909811974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:985; }","duration":"194.207191ms","start":"2026-03-19T21:48:28.988733Z","end":"2026-03-19T21:48:29.182941Z","steps":["trace[909811974] 'agreement among raft nodes before linearized reading' (duration: 194.07283ms)"],"step_count":1}