{"level":"warn","ts":"2026-07-25T13:33:35.311861Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T13:33:35.311947Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-07-25T13:33:35.312009Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T13:33:35.312022Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-07-25T13:33:35.31204Z","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-07-25T13:33:35.312635Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-07-25T13:33:35.312706Z","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.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-07-25T13:33:35.316731Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.638662ms"} {"level":"info","ts":"2026-07-25T13:33:35.32204Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-07-25T13:33:35.322105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T13:33:35.322121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-07-25T13:33:35.322129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T13:33:35.322135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-07-25T13:33:35.322164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-07-25T13:33:35.328736Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T13:33:35.329763Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T13:33:35.330979Z","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-07-25T13:33:35.332514Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T13:33:35.335855Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T13:33:35.335898Z","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-07-25T13:33:35.336039Z","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-07-25T13:33:35.336075Z","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-07-25T13:33:35.336733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-07-25T13:33:35.340887Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-07-25T13:33:35.341185Z","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-07-25T13:33:35.341284Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-07-25T13:33:35.341357Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-07-25T13:33:35.341867Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T13:33:35.341909Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T13:33:35.423232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T13:33:35.423293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T13:33:35.423502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-07-25T13:33:35.423536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-07-25T13:33:35.42361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-07-25T13:33:35.423693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-07-25T13:33:35.423745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-07-25T13:33:35.424674Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T13:33:35.424698Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T13:33:35.424726Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T13:33:35.4249Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T13:33:35.426277Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T13:33:35.42639Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T13:33:35.427519Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T13:33:35.427584Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T13:33:35.427758Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T13:33:35.427836Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T13:33:35.427902Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"warn","ts":"2026-07-25T13:33:42.007066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.825709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T13:33:42.007167Z","caller":"traceutil/trace.go:171","msg":"trace[573814928] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:292; }","duration":"280.893799ms","start":"2026-07-25T13:33:41.726261Z","end":"2026-07-25T13:33:42.007154Z","steps":["trace[573814928] 'read index received' (duration: 30.16µs)","trace[573814928] 'applied index is now lower than readState.Index' (duration: 280.862169ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:33:42.007258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.990773ms","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-07-25T13:33:42.007232Z","caller":"traceutil/trace.go:171","msg":"trace[1084951670] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"529.96148ms","start":"2026-07-25T13:33:41.477207Z","end":"2026-07-25T13:33:42.007169Z","steps":["trace[1084951670] 'process raft request' (duration: 211.596815ms)","trace[1084951670] 'compare' (duration: 317.559772ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:33:42.007375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:33:41.477187Z","time spent":"530.133595ms","remote":"127.0.0.1:41984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:33:42.007285Z","caller":"traceutil/trace.go:171","msg":"trace[809603437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:280; }","duration":"281.060835ms","start":"2026-07-25T13:33:41.726217Z","end":"2026-07-25T13:33:42.007278Z","steps":["trace[809603437] 'agreement among raft nodes before linearized reading' (duration: 280.976632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:33:42.514971Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153700830141013153,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T13:33:42.562393Z","caller":"traceutil/trace.go:171","msg":"trace[507110832] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:293; }","duration":"548.478438ms","start":"2026-07-25T13:33:42.013898Z","end":"2026-07-25T13:33:42.562376Z","steps":["trace[507110832] 'read index received' (duration: 546.537602ms)","trace[507110832] 'applied index is now lower than readState.Index' (duration: 1.940216ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:33:42.562622Z","caller":"traceutil/trace.go:171","msg":"trace[1345608689] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"551.310379ms","start":"2026-07-25T13:33:42.011305Z","end":"2026-07-25T13:33:42.562615Z","steps":["trace[1345608689] 'process raft request' (duration: 549.178697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:33:42.562791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:33:42.011287Z","time spent":"551.35602ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:33:42.562896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.006352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-25T13:33:42.562919Z","caller":"traceutil/trace.go:171","msg":"trace[752524735] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:281; }","duration":"549.056794ms","start":"2026-07-25T13:33:42.013856Z","end":"2026-07-25T13:33:42.562913Z","steps":["trace[752524735] 'agreement among raft nodes before linearized reading' (duration: 549.018193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:33:42.562932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:33:42.013843Z","time spent":"549.086535ms","remote":"127.0.0.1:41884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-25T13:33:42.563009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.396351ms","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-07-25T13:33:42.563027Z","caller":"traceutil/trace.go:171","msg":"trace[630328339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:281; }","duration":"324.450122ms","start":"2026-07-25T13:33:42.238573Z","end":"2026-07-25T13:33:42.563023Z","steps":["trace[630328339] 'agreement among raft nodes before linearized reading' (duration: 324.422411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:33:42.563039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:33:42.238553Z","time spent":"324.483613ms","remote":"127.0.0.1:42036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-25T13:33:46.295282Z","caller":"traceutil/trace.go:171","msg":"trace[1985067671] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"131.748625ms","start":"2026-07-25T13:33:46.163503Z","end":"2026-07-25T13:33:46.295252Z","steps":["trace[1985067671] 'process raft request' (duration: 131.58017ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:12.994424Z","caller":"traceutil/trace.go:171","msg":"trace[279665406] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"283.417585ms","start":"2026-07-25T13:34:12.710972Z","end":"2026-07-25T13:34:12.994389Z","steps":["trace[279665406] 'process raft request' (duration: 283.092738ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:12.994529Z","caller":"traceutil/trace.go:171","msg":"trace[257303627] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"229.378266ms","start":"2026-07-25T13:34:12.765127Z","end":"2026-07-25T13:34:12.994505Z","steps":["trace[257303627] 'process raft request' (duration: 229.137391ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:12.994646Z","caller":"traceutil/trace.go:171","msg":"trace[1315960232] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"233.507965ms","start":"2026-07-25T13:34:12.76113Z","end":"2026-07-25T13:34:12.994638Z","steps":["trace[1315960232] 'process raft request' (duration: 233.060685ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:12.994708Z","caller":"traceutil/trace.go:171","msg":"trace[1491798102] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"229.807625ms","start":"2026-07-25T13:34:12.764894Z","end":"2026-07-25T13:34:12.994701Z","steps":["trace[1491798102] 'process raft request' (duration: 229.337315ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:12.994719Z","caller":"traceutil/trace.go:171","msg":"trace[1706100086] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"190.637793ms","start":"2026-07-25T13:34:12.804049Z","end":"2026-07-25T13:34:12.994687Z","steps":["trace[1706100086] 'process raft request' (duration: 190.468689ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:12.994795Z","caller":"traceutil/trace.go:171","msg":"trace[1721344535] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"211.031737ms","start":"2026-07-25T13:34:12.783756Z","end":"2026-07-25T13:34:12.994788Z","steps":["trace[1721344535] 'process raft request' (duration: 210.596218ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:12.994919Z","caller":"traceutil/trace.go:171","msg":"trace[1756848921] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"200.957892ms","start":"2026-07-25T13:34:12.793935Z","end":"2026-07-25T13:34:12.994893Z","steps":["trace[1756848921] 'process raft request' (duration: 200.473402ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:12.994947Z","caller":"traceutil/trace.go:171","msg":"trace[1557329382] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"200.096304ms","start":"2026-07-25T13:34:12.794839Z","end":"2026-07-25T13:34:12.994935Z","steps":["trace[1557329382] 'process raft request' (duration: 199.640064ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:12.994956Z","caller":"traceutil/trace.go:171","msg":"trace[86411748] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"213.662583ms","start":"2026-07-25T13:34:12.781236Z","end":"2026-07-25T13:34:12.994899Z","steps":["trace[86411748] 'process raft request' (duration: 213.078001ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:12.994848Z","caller":"traceutil/trace.go:171","msg":"trace[207994265] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:502; }","duration":"225.085595ms","start":"2026-07-25T13:34:12.769608Z","end":"2026-07-25T13:34:12.994693Z","steps":["trace[207994265] 'read index received' (duration: 39.711973ms)","trace[207994265] 'applied index is now lower than readState.Index' (duration: 185.372162ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:34:12.99517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.446962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:12.995271Z","caller":"traceutil/trace.go:171","msg":"trace[2125238965] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"189.293444ms","start":"2026-07-25T13:34:12.805477Z","end":"2026-07-25T13:34:12.99477Z","steps":["trace[2125238965] 'process raft request' (duration: 189.10519ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:12.995356Z","caller":"traceutil/trace.go:171","msg":"trace[1492997292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"225.625296ms","start":"2026-07-25T13:34:12.769583Z","end":"2026-07-25T13:34:12.995209Z","steps":["trace[1492997292] 'agreement among raft nodes before linearized reading' (duration: 225.373271ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:12.995264Z","caller":"traceutil/trace.go:171","msg":"trace[395952298] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"200.597104ms","start":"2026-07-25T13:34:12.794118Z","end":"2026-07-25T13:34:12.994716Z","steps":["trace[395952298] 'process raft request' (duration: 200.322869ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:13.064798Z","caller":"traceutil/trace.go:171","msg":"trace[844160559] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"231.06086ms","start":"2026-07-25T13:34:12.833719Z","end":"2026-07-25T13:34:13.06478Z","steps":["trace[844160559] 'process raft request' (duration: 230.761954ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:13.065103Z","caller":"traceutil/trace.go:171","msg":"trace[2009024168] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"118.796404ms","start":"2026-07-25T13:34:12.946075Z","end":"2026-07-25T13:34:13.064871Z","steps":["trace[2009024168] 'process raft request' (duration: 118.60435ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:13.341311Z","caller":"traceutil/trace.go:171","msg":"trace[2092179007] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"334.592079ms","start":"2026-07-25T13:34:13.00669Z","end":"2026-07-25T13:34:13.341282Z","steps":["trace[2092179007] 'process raft request' (duration: 334.407705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:13.341713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:13.006684Z","time spent":"334.949307ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:34:13.341754Z","caller":"traceutil/trace.go:171","msg":"trace[136728828] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"329.311078ms","start":"2026-07-25T13:34:13.012413Z","end":"2026-07-25T13:34:13.341724Z","steps":["trace[136728828] 'process raft request' (duration: 329.103784ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:13.341228Z","caller":"traceutil/trace.go:171","msg":"trace[907290637] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"334.740892ms","start":"2026-07-25T13:34:13.006459Z","end":"2026-07-25T13:34:13.3412Z","steps":["trace[907290637] 'process raft request' (duration: 326.819006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:13.341892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:13.006443Z","time spent":"335.424137ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T13:34:13.341926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:13.012386Z","time spent":"329.45129ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:34:13.341895Z","caller":"traceutil/trace.go:171","msg":"trace[1161778604] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"335.060158ms","start":"2026-07-25T13:34:13.00678Z","end":"2026-07-25T13:34:13.34184Z","steps":["trace[1161778604] 'process raft request' (duration: 334.422384ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:13.341965Z","caller":"traceutil/trace.go:171","msg":"trace[1262756508] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"335.13141ms","start":"2026-07-25T13:34:13.006817Z","end":"2026-07-25T13:34:13.341948Z","steps":["trace[1262756508] 'process raft request' (duration: 334.437025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:13.342084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:13.006813Z","time spent":"335.198622ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T13:34:13.342104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:13.006774Z","time spent":"335.226912ms","remote":"127.0.0.1:42314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:34:13.342128Z","caller":"traceutil/trace.go:171","msg":"trace[436960360] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"331.579176ms","start":"2026-07-25T13:34:13.010539Z","end":"2026-07-25T13:34:13.342118Z","steps":["trace[436960360] 'process raft request' (duration: 330.921602ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:13.342211Z","caller":"traceutil/trace.go:171","msg":"trace[1010066982] transaction","detail":"{read_only:false; number_of_response:1; response_revision:501; }","duration":"332.846113ms","start":"2026-07-25T13:34:13.009264Z","end":"2026-07-25T13:34:13.34211Z","steps":["trace[1010066982] 'process raft request' (duration: 332.140188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:13.342256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:13.010531Z","time spent":"331.623307ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:34:13.342335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:13.009259Z","time spent":"333.001696ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:34:13.342341Z","caller":"traceutil/trace.go:171","msg":"trace[1490379739] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"333.125948ms","start":"2026-07-25T13:34:13.009204Z","end":"2026-07-25T13:34:13.34233Z","steps":["trace[1490379739] 'process raft request' (duration: 332.111397ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:13.342372Z","caller":"traceutil/trace.go:171","msg":"trace[1839212497] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"328.903629ms","start":"2026-07-25T13:34:13.013441Z","end":"2026-07-25T13:34:13.342345Z","steps":["trace[1839212497] 'process raft request' (duration: 328.115473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:13.342429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:13.009194Z","time spent":"333.179349ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:34:13.34258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:13.01343Z","time spent":"329.023062ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:34:13.342615Z","caller":"traceutil/trace.go:171","msg":"trace[2111952526] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"324.65418ms","start":"2026-07-25T13:34:13.017945Z","end":"2026-07-25T13:34:13.342599Z","steps":["trace[2111952526] 'process raft request' (duration: 324.294642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:13.342675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:13.017917Z","time spent":"324.724071ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:34:13.342707Z","caller":"traceutil/trace.go:171","msg":"trace[2125904556] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:515; }","duration":"134.993293ms","start":"2026-07-25T13:34:13.207698Z","end":"2026-07-25T13:34:13.342691Z","steps":["trace[2125904556] 'read index received' (duration: 125.613816ms)","trace[2125904556] 'applied index is now lower than readState.Index' (duration: 9.378417ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:34:13.342774Z","caller":"traceutil/trace.go:171","msg":"trace[1478419414] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"324.022886ms","start":"2026-07-25T13:34:13.01874Z","end":"2026-07-25T13:34:13.342763Z","steps":["trace[1478419414] 'process raft request' (duration: 323.825782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:13.34283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:13.018732Z","time spent":"324.064928ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:34:13.342893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.197659ms","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-07-25T13:34:13.34293Z","caller":"traceutil/trace.go:171","msg":"trace[1044623075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"135.304201ms","start":"2026-07-25T13:34:13.207614Z","end":"2026-07-25T13:34:13.342918Z","steps":["trace[1044623075] 'agreement among raft nodes before linearized reading' (duration: 135.131906ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:13.343025Z","caller":"traceutil/trace.go:171","msg":"trace[1337208739] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"215.688904ms","start":"2026-07-25T13:34:13.127325Z","end":"2026-07-25T13:34:13.343014Z","steps":["trace[1337208739] 'process raft request' (duration: 215.334396ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:19.673867Z","caller":"traceutil/trace.go:171","msg":"trace[1242359021] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"334.580986ms","start":"2026-07-25T13:34:19.339252Z","end":"2026-07-25T13:34:19.673833Z","steps":["trace[1242359021] 'read index received' (duration: 331.830991ms)","trace[1242359021] 'applied index is now lower than readState.Index' (duration: 2.748845ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:34:19.673957Z","caller":"traceutil/trace.go:171","msg":"trace[2082795174] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"502.549793ms","start":"2026-07-25T13:34:19.171349Z","end":"2026-07-25T13:34:19.673898Z","steps":["trace[2082795174] 'process raft request' (duration: 500.585094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:19.674074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.8112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-26ksk\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-25T13:34:19.674129Z","caller":"traceutil/trace.go:171","msg":"trace[1070885964] range","detail":"{range_begin:/registry/pods/kube-system/cilium-26ksk; range_end:; response_count:1; response_revision:581; }","duration":"334.926783ms","start":"2026-07-25T13:34:19.339189Z","end":"2026-07-25T13:34:19.674116Z","steps":["trace[1070885964] 'agreement among raft nodes before linearized reading' (duration: 334.7706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:19.674157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:19.339163Z","time spent":"334.988824ms","remote":"127.0.0.1:41960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-26ksk\" "} {"level":"warn","ts":"2026-07-25T13:34:19.674166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.000874ms","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-07-25T13:34:19.674204Z","caller":"traceutil/trace.go:171","msg":"trace[1904952040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"290.068916ms","start":"2026-07-25T13:34:19.384122Z","end":"2026-07-25T13:34:19.674191Z","steps":["trace[1904952040] 'agreement among raft nodes before linearized reading' (duration: 289.975824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:19.674231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:19.171322Z","time spent":"502.769016ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:34:19.801007Z","caller":"traceutil/trace.go:171","msg":"trace[557359912] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"108.003858ms","start":"2026-07-25T13:34:19.692965Z","end":"2026-07-25T13:34:19.800969Z","steps":["trace[557359912] 'process raft request' (duration: 107.883806ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:19.801055Z","caller":"traceutil/trace.go:171","msg":"trace[1059428674] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"121.031408ms","start":"2026-07-25T13:34:19.679965Z","end":"2026-07-25T13:34:19.800997Z","steps":["trace[1059428674] 'process raft request' (duration: 120.678552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:20.65726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.751505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T13:34:20.657392Z","caller":"traceutil/trace.go:171","msg":"trace[155434939] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:606; }","duration":"887.366601ms","start":"2026-07-25T13:34:19.769996Z","end":"2026-07-25T13:34:20.657362Z","steps":["trace[155434939] 'read index received' (duration: 30.697874ms)","trace[155434939] 'applied index is now lower than readState.Index' (duration: 856.667877ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:34:20.657462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.473693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:20.657485Z","caller":"traceutil/trace.go:171","msg":"trace[2050924676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"887.501503ms","start":"2026-07-25T13:34:19.769977Z","end":"2026-07-25T13:34:20.657478Z","steps":["trace[2050924676] 'agreement among raft nodes before linearized reading' (duration: 887.435842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:20.65751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:19.769924Z","time spent":"887.579875ms","remote":"127.0.0.1:41776","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-07-25T13:34:20.657498Z","caller":"traceutil/trace.go:171","msg":"trace[638657267] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"916.510903ms","start":"2026-07-25T13:34:19.740957Z","end":"2026-07-25T13:34:20.657468Z","steps":["trace[638657267] 'process raft request' (duration: 432.490854ms)","trace[638657267] 'compare' (duration: 483.58499ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:34:20.657643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:19.74094Z","time spent":"916.623686ms","remote":"127.0.0.1:41984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T13:34:22.72875Z","caller":"traceutil/trace.go:171","msg":"trace[1668056932] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"150.064413ms","start":"2026-07-25T13:34:22.578649Z","end":"2026-07-25T13:34:22.728713Z","steps":["trace[1668056932] 'process raft request' (duration: 147.280998ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:32.213068Z","caller":"traceutil/trace.go:171","msg":"trace[1007352545] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"117.254136ms","start":"2026-07-25T13:34:32.095791Z","end":"2026-07-25T13:34:32.213045Z","steps":["trace[1007352545] 'process raft request' (duration: 117.133864ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:32.696086Z","caller":"traceutil/trace.go:171","msg":"trace[1778816193] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"122.53326ms","start":"2026-07-25T13:34:32.573528Z","end":"2026-07-25T13:34:32.696061Z","steps":["trace[1778816193] 'process raft request' (duration: 122.387957ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:32.793884Z","caller":"traceutil/trace.go:171","msg":"trace[1315992942] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"129.605128ms","start":"2026-07-25T13:34:32.664263Z","end":"2026-07-25T13:34:32.793868Z","steps":["trace[1315992942] 'process raft request' (duration: 129.488276ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:34.365202Z","caller":"traceutil/trace.go:171","msg":"trace[1096294068] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"143.766552ms","start":"2026-07-25T13:34:34.221404Z","end":"2026-07-25T13:34:34.36517Z","steps":["trace[1096294068] 'process raft request' (duration: 143.510718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:37.48993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.184897ms","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-07-25T13:34:37.490033Z","caller":"traceutil/trace.go:171","msg":"trace[1505282323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"396.333639ms","start":"2026-07-25T13:34:37.093669Z","end":"2026-07-25T13:34:37.490002Z","steps":["trace[1505282323] 'range keys from in-memory index tree' (duration: 396.002324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:37.490012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.395863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T13:34:37.490078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:37.093635Z","time spent":"396.43388ms","remote":"127.0.0.1:42036","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":"info","ts":"2026-07-25T13:34:37.490119Z","caller":"traceutil/trace.go:171","msg":"trace[805426774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"719.595586ms","start":"2026-07-25T13:34:36.7705Z","end":"2026-07-25T13:34:37.490096Z","steps":["trace[805426774] 'range keys from in-memory index tree' (duration: 719.291661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:37.490169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:36.770475Z","time spent":"719.684787ms","remote":"127.0.0.1:41784","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-07-25T13:34:37.491181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.58567ms","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-07-25T13:34:37.491231Z","caller":"traceutil/trace.go:171","msg":"trace[1449395870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"563.684412ms","start":"2026-07-25T13:34:36.927535Z","end":"2026-07-25T13:34:37.49122Z","steps":["trace[1449395870] 'range keys from in-memory index tree' (duration: 562.211646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:37.491346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:34:36.927511Z","time spent":"563.790013ms","remote":"127.0.0.1:42036","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-07-25T13:34:37.733943Z","caller":"traceutil/trace.go:171","msg":"trace[1435729796] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"242.613502ms","start":"2026-07-25T13:34:37.491308Z","end":"2026-07-25T13:34:37.733922Z","steps":["trace[1435729796] 'read index received' (duration: 242.48343ms)","trace[1435729796] 'applied index is now lower than readState.Index' (duration: 129.062µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:34:37.734081Z","caller":"traceutil/trace.go:171","msg":"trace[713543843] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"265.801363ms","start":"2026-07-25T13:34:37.468232Z","end":"2026-07-25T13:34:37.734034Z","steps":["trace[713543843] 'process raft request' (duration: 265.553639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:37.734094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.738394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:37.734363Z","caller":"traceutil/trace.go:171","msg":"trace[1003933400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"243.07748ms","start":"2026-07-25T13:34:37.491239Z","end":"2026-07-25T13:34:37.734316Z","steps":["trace[1003933400] 'agreement among raft nodes before linearized reading' (duration: 242.763694ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:37.738965Z","caller":"traceutil/trace.go:171","msg":"trace[335165102] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"243.934166ms","start":"2026-07-25T13:34:37.494994Z","end":"2026-07-25T13:34:37.738928Z","steps":["trace[335165102] 'process raft request' (duration: 243.887075ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:37.739284Z","caller":"traceutil/trace.go:171","msg":"trace[1218380721] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"244.689368ms","start":"2026-07-25T13:34:37.494566Z","end":"2026-07-25T13:34:37.739255Z","steps":["trace[1218380721] 'process raft request' (duration: 244.17223ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:41.381433Z","caller":"traceutil/trace.go:171","msg":"trace[631583241] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"102.51518ms","start":"2026-07-25T13:34:41.278895Z","end":"2026-07-25T13:34:41.38141Z","steps":["trace[631583241] 'process raft request' (duration: 102.357577ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:41.465987Z","caller":"traceutil/trace.go:171","msg":"trace[758710392] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"165.563329ms","start":"2026-07-25T13:34:41.3004Z","end":"2026-07-25T13:34:41.465964Z","steps":["trace[758710392] 'process raft request' (duration: 108.049894ms)","trace[758710392] 'compare' (duration: 57.415943ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:34:41.490831Z","caller":"traceutil/trace.go:171","msg":"trace[2073872306] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"101.556133ms","start":"2026-07-25T13:34:41.389216Z","end":"2026-07-25T13:34:41.490772Z","steps":["trace[2073872306] 'process raft request' (duration: 101.103546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:41.941326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.009532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T13:34:41.941445Z","caller":"traceutil/trace.go:171","msg":"trace[137429928] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"213.004703ms","start":"2026-07-25T13:34:41.728419Z","end":"2026-07-25T13:34:41.941424Z","steps":["trace[137429928] 'process raft request' (duration: 101.817548ms)","trace[137429928] 'compare' (duration: 110.897951ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:34:41.942Z","caller":"traceutil/trace.go:171","msg":"trace[1764552139] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:822; }","duration":"171.202893ms","start":"2026-07-25T13:34:41.77077Z","end":"2026-07-25T13:34:41.941973Z","steps":["trace[1764552139] 'read index received' (duration: 59.487839ms)","trace[1764552139] 'applied index is now lower than readState.Index' (duration: 111.714084ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:34:41.942309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.535948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:34:41.94236Z","caller":"traceutil/trace.go:171","msg":"trace[1921196795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"171.63389ms","start":"2026-07-25T13:34:41.770713Z","end":"2026-07-25T13:34:41.942347Z","steps":["trace[1921196795] 'agreement among raft nodes before linearized reading' (duration: 171.529339ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:41.94254Z","caller":"traceutil/trace.go:171","msg":"trace[1266469458] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"210.511522ms","start":"2026-07-25T13:34:41.731988Z","end":"2026-07-25T13:34:41.942499Z","steps":["trace[1266469458] 'process raft request' (duration: 209.935122ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:41.942565Z","caller":"traceutil/trace.go:171","msg":"trace[732264341] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"213.155926ms","start":"2026-07-25T13:34:41.729386Z","end":"2026-07-25T13:34:41.942542Z","steps":["trace[732264341] 'process raft request' (duration: 212.385993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:41.942772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.006924ms","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-07-25T13:34:41.942815Z","caller":"traceutil/trace.go:171","msg":"trace[2102950149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"101.083956ms","start":"2026-07-25T13:34:41.841718Z","end":"2026-07-25T13:34:41.942802Z","steps":["trace[2102950149] 'agreement among raft nodes before linearized reading' (duration: 100.948213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:34:41.942474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.317339ms","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-07-25T13:34:41.943659Z","caller":"traceutil/trace.go:171","msg":"trace[967250420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"158.54105ms","start":"2026-07-25T13:34:41.785095Z","end":"2026-07-25T13:34:41.943636Z","steps":["trace[967250420] 'agreement among raft nodes before linearized reading' (duration: 157.190337ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:42.108641Z","caller":"traceutil/trace.go:171","msg":"trace[1157450752] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"160.339148ms","start":"2026-07-25T13:34:41.948269Z","end":"2026-07-25T13:34:42.108608Z","steps":["trace[1157450752] 'process raft request' (duration: 115.137104ms)","trace[1157450752] 'compare' (duration: 45.070791ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:34:42.127103Z","caller":"traceutil/trace.go:171","msg":"trace[2108560962] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"178.806135ms","start":"2026-07-25T13:34:41.948269Z","end":"2026-07-25T13:34:42.127075Z","steps":["trace[2108560962] 'process raft request' (duration: 178.577632ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:42.127092Z","caller":"traceutil/trace.go:171","msg":"trace[1989964157] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"178.624203ms","start":"2026-07-25T13:34:41.948437Z","end":"2026-07-25T13:34:42.127061Z","steps":["trace[1989964157] 'process raft request' (duration: 178.550412ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:42.127279Z","caller":"traceutil/trace.go:171","msg":"trace[779863819] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"178.415349ms","start":"2026-07-25T13:34:41.948838Z","end":"2026-07-25T13:34:42.127254Z","steps":["trace[779863819] 'process raft request' (duration: 178.183605ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:34:42.127225Z","caller":"traceutil/trace.go:171","msg":"trace[539655442] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:825; }","duration":"133.649848ms","start":"2026-07-25T13:34:41.993549Z","end":"2026-07-25T13:34:42.127199Z","steps":["trace[539655442] 'read index received' (duration: 69.998677ms)","trace[539655442] 'applied index is now lower than readState.Index' (duration: 63.649481ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:34:42.127402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.833811ms","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-07-25T13:34:42.127437Z","caller":"traceutil/trace.go:171","msg":"trace[1343361535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"133.904693ms","start":"2026-07-25T13:34:41.993524Z","end":"2026-07-25T13:34:42.127428Z","steps":["trace[1343361535] 'agreement among raft nodes before linearized reading' (duration: 133.74939ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:35:32.613788Z","caller":"traceutil/trace.go:171","msg":"trace[1407547333] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"122.613613ms","start":"2026-07-25T13:35:32.491145Z","end":"2026-07-25T13:35:32.613758Z","steps":["trace[1407547333] 'process raft request' (duration: 76.607388ms)","trace[1407547333] 'compare' (duration: 45.883903ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:35:54.749715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.170049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-25T13:35:54.749737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.072715ms","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-07-25T13:35:54.749792Z","caller":"traceutil/trace.go:171","msg":"trace[766938402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1145; }","duration":"119.288091ms","start":"2026-07-25T13:35:54.630486Z","end":"2026-07-25T13:35:54.749775Z","steps":["trace[766938402] 'range keys from in-memory index tree' (duration: 119.001498ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:35:54.749827Z","caller":"traceutil/trace.go:171","msg":"trace[46923004] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1145; }","duration":"131.210977ms","start":"2026-07-25T13:35:54.618598Z","end":"2026-07-25T13:35:54.749809Z","steps":["trace[46923004] 'range keys from in-memory index tree' (duration: 130.896683ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:35:54.935531Z","caller":"traceutil/trace.go:171","msg":"trace[46845557] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"181.752368ms","start":"2026-07-25T13:35:54.753756Z","end":"2026-07-25T13:35:54.935509Z","steps":["trace[46845557] 'process raft request' (duration: 181.607357ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:35:54.992795Z","caller":"traceutil/trace.go:171","msg":"trace[999375728] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"238.783514ms","start":"2026-07-25T13:35:54.753966Z","end":"2026-07-25T13:35:54.99275Z","steps":["trace[999375728] 'process raft request' (duration: 213.951778ms)","trace[999375728] 'compare' (duration: 24.723785ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:35:54.992935Z","caller":"traceutil/trace.go:171","msg":"trace[1484074316] linearizableReadLoop","detail":"{readStateIndex:1194; appliedIndex:1191; }","duration":"222.475736ms","start":"2026-07-25T13:35:54.770438Z","end":"2026-07-25T13:35:54.992913Z","steps":["trace[1484074316] 'read index received' (duration: 164.958425ms)","trace[1484074316] 'applied index is now lower than readState.Index' (duration: 57.515481ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:35:54.993029Z","caller":"traceutil/trace.go:171","msg":"trace[832726812] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"237.875563ms","start":"2026-07-25T13:35:54.755147Z","end":"2026-07-25T13:35:54.993022Z","steps":["trace[832726812] 'process raft request' (duration: 237.56869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:35:54.993124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.910252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-25T13:35:54.993174Z","caller":"traceutil/trace.go:171","msg":"trace[502637429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1148; }","duration":"116.006364ms","start":"2026-07-25T13:35:54.877156Z","end":"2026-07-25T13:35:54.993163Z","steps":["trace[502637429] 'agreement among raft nodes before linearized reading' (duration: 115.871932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:35:54.993192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.74684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:35:54.99322Z","caller":"traceutil/trace.go:171","msg":"trace[1208135684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1148; }","duration":"222.82511ms","start":"2026-07-25T13:35:54.770387Z","end":"2026-07-25T13:35:54.993212Z","steps":["trace[1208135684] 'agreement among raft nodes before linearized reading' (duration: 222.756989ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:36:39.983854Z","caller":"traceutil/trace.go:171","msg":"trace[1778964363] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"108.078948ms","start":"2026-07-25T13:36:39.875748Z","end":"2026-07-25T13:36:39.983827Z","steps":["trace[1778964363] 'process raft request' (duration: 107.850016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:36:54.904985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.727137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:36:54.905106Z","caller":"traceutil/trace.go:171","msg":"trace[426325069] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1381; }","duration":"169.904908ms","start":"2026-07-25T13:36:54.735181Z","end":"2026-07-25T13:36:54.905086Z","steps":["trace[426325069] 'range keys from in-memory index tree' (duration: 169.657735ms)"],"step_count":1}