{"level":"warn","ts":"2026-05-26T15:49:52.127007Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T15:49:52.127096Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.73: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.73:2380","--initial-cluster=instance=https://199.19.213.73:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.73:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.73: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-05-26T15:49:52.127162Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T15:49:52.12717Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-05-26T15:49:52.127185Z","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-05-26T15:49:52.127794Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"]} {"level":"info","ts":"2026-05-26T15:49:52.127868Z","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.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.73: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-05-26T15:49:52.135329Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.250553ms"} {"level":"info","ts":"2026-05-26T15:49:52.153741Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"34a8f480fa9ab6cb","cluster-id":"4820536600e0938e"} {"level":"info","ts":"2026-05-26T15:49:52.153838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T15:49:52.153861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 0"} {"level":"info","ts":"2026-05-26T15:49:52.153874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 34a8f480fa9ab6cb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T15:49:52.153891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 1"} {"level":"info","ts":"2026-05-26T15:49:52.153935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"warn","ts":"2026-05-26T15:49:52.164162Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T15:49:52.165674Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T15:49:52.167489Z","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-05-26T15:49:52.169912Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"34a8f480fa9ab6cb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T15:49:52.170041Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"34a8f480fa9ab6cb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T15:49:52.170055Z","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-05-26T15:49:52.170183Z","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-05-26T15:49:52.170213Z","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-05-26T15:49:52.171936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"info","ts":"2026-05-26T15:49:52.172314Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","added-peer-id":"34a8f480fa9ab6cb","added-peer-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-05-26T15:49:52.174895Z","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-05-26T15:49:52.174998Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-05-26T15:49:52.175036Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-05-26T15:49:52.175345Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"34a8f480fa9ab6cb","initial-advertise-peer-urls":["https://199.19.213.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T15:49:52.175436Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T15:49:52.855429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T15:49:52.855485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T15:49:52.855529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgPreVoteResp from 34a8f480fa9ab6cb at term 1"} {"level":"info","ts":"2026-05-26T15:49:52.855547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became candidate at term 2"} {"level":"info","ts":"2026-05-26T15:49:52.855558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgVoteResp from 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-05-26T15:49:52.855574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became leader at term 2"} {"level":"info","ts":"2026-05-26T15:49:52.855587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 34a8f480fa9ab6cb elected leader 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-05-26T15:49:52.857075Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"34a8f480fa9ab6cb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.73:2379]}","request-path":"/0/members/34a8f480fa9ab6cb/attributes","cluster-id":"4820536600e0938e","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T15:49:52.859635Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T15:49:52.859697Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T15:49:52.859862Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:49:52.860233Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T15:49:52.86044Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T15:49:52.861083Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:49:52.861196Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:49:52.861232Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:49:52.863652Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.73:2379"} {"level":"info","ts":"2026-05-26T15:49:52.863786Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T15:49:55.350199Z","caller":"traceutil/trace.go:171","msg":"trace[93041628] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:81; }","duration":"118.033926ms","start":"2026-05-26T15:49:55.23212Z","end":"2026-05-26T15:49:55.350154Z","steps":["trace[93041628] 'read index received' (duration: 18.606069ms)","trace[93041628] 'applied index is now lower than readState.Index' (duration: 99.426297ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:49:55.350414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.282328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T15:49:55.350526Z","caller":"traceutil/trace.go:171","msg":"trace[1018903381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:78; }","duration":"118.456098ms","start":"2026-05-26T15:49:55.232053Z","end":"2026-05-26T15:49:55.350509Z","steps":["trace[1018903381] 'agreement among raft nodes before linearized reading' (duration: 118.267037ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:49:55.350423Z","caller":"traceutil/trace.go:171","msg":"trace[522549156] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"146.221413ms","start":"2026-05-26T15:49:55.204178Z","end":"2026-05-26T15:49:55.3504Z","steps":["trace[522549156] 'process raft request' (duration: 46.547424ms)","trace[522549156] 'compare' (duration: 99.249036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:49:56.552643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.160024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-26T15:49:56.552968Z","caller":"traceutil/trace.go:171","msg":"trace[1552151560] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"1.185263854s","start":"2026-05-26T15:49:55.367681Z","end":"2026-05-26T15:49:56.552944Z","steps":["trace[1552151560] 'process raft request' (duration: 697.181734ms)","trace[1552151560] 'compare' (duration: 486.945623ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:49:56.553027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:49:55.36767Z","time spent":"1.185333244s","remote":"127.0.0.1:55578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":621,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T15:49:57.080308Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.015459338s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-26T15:49:57.57045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.89077ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-05-26T15:49:57.570781Z","caller":"traceutil/trace.go:171","msg":"trace[765009980] linearizableReadLoop","detail":"{readStateIndex:86; appliedIndex:83; }","duration":"1.712127426s","start":"2026-05-26T15:49:55.858628Z","end":"2026-05-26T15:49:57.570755Z","steps":["trace[765009980] 'read index received' (duration: 206.250986ms)","trace[765009980] 'applied index is now lower than readState.Index' (duration: 1.50587543s)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:49:57.570795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:49:55.48209Z","time spent":"2.088695798s","remote":"127.0.0.1:45038","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-26T15:49:57.571262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338839588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T15:49:57.571387Z","caller":"traceutil/trace.go:171","msg":"trace[756412621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:81; }","duration":"1.339016458s","start":"2026-05-26T15:49:56.232341Z","end":"2026-05-26T15:49:57.571358Z","steps":["trace[756412621] 'agreement among raft nodes before linearized reading' (duration: 1.338666747s)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:49:57.571002Z","caller":"traceutil/trace.go:171","msg":"trace[129240196] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"1.727704697s","start":"2026-05-26T15:49:55.843282Z","end":"2026-05-26T15:49:57.570986Z","steps":["trace[129240196] 'process raft request' (duration: 1.727339396s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:49:57.57113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005449038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T15:49:57.571638Z","caller":"traceutil/trace.go:171","msg":"trace[1885435897] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:0; response_revision:81; }","duration":"1.005986161s","start":"2026-05-26T15:49:56.565631Z","end":"2026-05-26T15:49:57.571617Z","steps":["trace[1885435897] 'agreement among raft nodes before linearized reading' (duration: 1.005405318s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:49:57.57169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:49:56.565613Z","time spent":"1.006064122s","remote":"127.0.0.1:55578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:basic-user\" "} {"level":"warn","ts":"2026-05-26T15:49:57.571607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:49:55.843252Z","time spent":"1.728262451s","remote":"127.0.0.1:45154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T15:49:57.571183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299202715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-26T15:49:57.571859Z","caller":"traceutil/trace.go:171","msg":"trace[764302258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:81; }","duration":"1.29993181s","start":"2026-05-26T15:49:56.27192Z","end":"2026-05-26T15:49:57.571852Z","steps":["trace[764302258] 'agreement among raft nodes before linearized reading' (duration: 1.299230965s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:49:57.57188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:49:56.27189Z","time spent":"1.29998475s","remote":"127.0.0.1:45232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T15:49:57.571169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.295194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T15:49:57.571987Z","caller":"traceutil/trace.go:171","msg":"trace[601872343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:81; }","duration":"297.15654ms","start":"2026-05-26T15:49:57.274819Z","end":"2026-05-26T15:49:57.571976Z","steps":["trace[601872343] 'agreement among raft nodes before linearized reading' (duration: 296.258885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:49:57.571222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299365416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"info","ts":"2026-05-26T15:49:57.5721Z","caller":"traceutil/trace.go:171","msg":"trace[269405433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:81; }","duration":"1.300264881s","start":"2026-05-26T15:49:56.271829Z","end":"2026-05-26T15:49:57.572094Z","steps":["trace[269405433] 'agreement among raft nodes before linearized reading' (duration: 1.299371816s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:49:57.57212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:49:56.271817Z","time spent":"1.300297151s","remote":"127.0.0.1:45232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":452,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T15:49:57.57122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.712544398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T15:49:57.572246Z","caller":"traceutil/trace.go:171","msg":"trace[1677861767] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:81; }","duration":"1.713662495s","start":"2026-05-26T15:49:55.858577Z","end":"2026-05-26T15:49:57.572239Z","steps":["trace[1677861767] 'agreement among raft nodes before linearized reading' (duration: 1.712500048s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:49:57.572271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:49:55.858551Z","time spent":"1.713714315s","remote":"127.0.0.1:45232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-05-26T15:49:57.571433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:49:56.232319Z","time spent":"1.33910608s","remote":"127.0.0.1:44936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T15:49:57.721274Z","caller":"traceutil/trace.go:171","msg":"trace[553766875] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"147.088611ms","start":"2026-05-26T15:49:57.574153Z","end":"2026-05-26T15:49:57.721241Z","steps":["trace[553766875] 'process raft request' (duration: 137.707467ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:49:57.721357Z","caller":"traceutil/trace.go:171","msg":"trace[1538968477] linearizableReadLoop","detail":"{readStateIndex:87; appliedIndex:86; }","duration":"145.574373ms","start":"2026-05-26T15:49:57.575761Z","end":"2026-05-26T15:49:57.721335Z","steps":["trace[1538968477] 'read index received' (duration: 136.198838ms)","trace[1538968477] 'applied index is now lower than readState.Index' (duration: 9.373095ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:49:57.721414Z","caller":"traceutil/trace.go:171","msg":"trace[1965673404] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"144.105583ms","start":"2026-05-26T15:49:57.577283Z","end":"2026-05-26T15:49:57.721389Z","steps":["trace[1965673404] 'process raft request' (duration: 144.063623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:49:57.72157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.778875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-05-26T15:49:57.721616Z","caller":"traceutil/trace.go:171","msg":"trace[1623606631] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:85; }","duration":"145.892445ms","start":"2026-05-26T15:49:57.575712Z","end":"2026-05-26T15:49:57.721604Z","steps":["trace[1623606631] 'agreement among raft nodes before linearized reading' (duration: 145.685583ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:49:57.721738Z","caller":"traceutil/trace.go:171","msg":"trace[204564763] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"145.840513ms","start":"2026-05-26T15:49:57.575861Z","end":"2026-05-26T15:49:57.721701Z","steps":["trace[204564763] 'process raft request' (duration: 145.37282ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:49:57.72179Z","caller":"traceutil/trace.go:171","msg":"trace[1226347247] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"145.10874ms","start":"2026-05-26T15:49:57.576664Z","end":"2026-05-26T15:49:57.721773Z","steps":["trace[1226347247] 'process raft request' (duration: 144.632486ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:50:21.968717Z","caller":"traceutil/trace.go:171","msg":"trace[431914964] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"108.836743ms","start":"2026-05-26T15:50:21.859853Z","end":"2026-05-26T15:50:21.96869Z","steps":["trace[431914964] 'read index received' (duration: 108.643079ms)","trace[431914964] 'applied index is now lower than readState.Index' (duration: 192.584µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:50:21.968862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.977745ms","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-05-26T15:50:21.968898Z","caller":"traceutil/trace.go:171","msg":"trace[65124745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"109.064376ms","start":"2026-05-26T15:50:21.859822Z","end":"2026-05-26T15:50:21.968886Z","steps":["trace[65124745] 'agreement among raft nodes before linearized reading' (duration: 108.973514ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:50:21.969031Z","caller":"traceutil/trace.go:171","msg":"trace[1878058069] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"116.677643ms","start":"2026-05-26T15:50:21.852312Z","end":"2026-05-26T15:50:21.968989Z","steps":["trace[1878058069] 'process raft request' (duration: 116.203684ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:50:23.475967Z","caller":"traceutil/trace.go:171","msg":"trace[1933244005] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"163.664338ms","start":"2026-05-26T15:50:23.312275Z","end":"2026-05-26T15:50:23.47594Z","steps":["trace[1933244005] 'process raft request' (duration: 163.478475ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:50:31.210814Z","caller":"traceutil/trace.go:171","msg":"trace[188857567] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"115.800795ms","start":"2026-05-26T15:50:31.094984Z","end":"2026-05-26T15:50:31.210785Z","steps":["trace[188857567] 'process raft request' (duration: 115.634022ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:50:32.134972Z","caller":"traceutil/trace.go:171","msg":"trace[1548273338] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"106.752255ms","start":"2026-05-26T15:50:32.027974Z","end":"2026-05-26T15:50:32.134726Z","steps":["trace[1548273338] 'process raft request' (duration: 106.589672ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:50:32.324214Z","caller":"traceutil/trace.go:171","msg":"trace[240924283] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"293.870872ms","start":"2026-05-26T15:50:32.030316Z","end":"2026-05-26T15:50:32.324187Z","steps":["trace[240924283] 'process raft request' (duration: 293.72443ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:50:37.907989Z","caller":"traceutil/trace.go:171","msg":"trace[217499215] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"121.083461ms","start":"2026-05-26T15:50:37.786869Z","end":"2026-05-26T15:50:37.907952Z","steps":["trace[217499215] 'process raft request' (duration: 29.260627ms)","trace[217499215] 'compare' (duration: 91.606401ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:50:37.934901Z","caller":"traceutil/trace.go:171","msg":"trace[225020656] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"105.548115ms","start":"2026-05-26T15:50:37.829326Z","end":"2026-05-26T15:50:37.934875Z","steps":["trace[225020656] 'process raft request' (duration: 104.864124ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:50:38.464819Z","caller":"traceutil/trace.go:171","msg":"trace[321672502] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"469.206226ms","start":"2026-05-26T15:50:37.995595Z","end":"2026-05-26T15:50:38.464801Z","steps":["trace[321672502] 'process raft request' (duration: 466.19816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:50:38.465106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:50:37.995582Z","time spent":"469.299597ms","remote":"127.0.0.1:45000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:50:38.472487Z","caller":"traceutil/trace.go:171","msg":"trace[1216308615] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"467.347856ms","start":"2026-05-26T15:50:38.005112Z","end":"2026-05-26T15:50:38.47246Z","steps":["trace[1216308615] 'read index received' (duration: 456.695314ms)","trace[1216308615] 'applied index is now lower than readState.Index' (duration: 10.651742ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:50:38.472621Z","caller":"traceutil/trace.go:171","msg":"trace[1059324676] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"284.785918ms","start":"2026-05-26T15:50:38.187826Z","end":"2026-05-26T15:50:38.472612Z","steps":["trace[1059324676] 'process raft request' (duration: 284.603476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:50:38.472893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.00257ms","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-05-26T15:50:38.472941Z","caller":"traceutil/trace.go:171","msg":"trace[1524937258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"252.090142ms","start":"2026-05-26T15:50:38.220838Z","end":"2026-05-26T15:50:38.472928Z","steps":["trace[1524937258] 'agreement among raft nodes before linearized reading' (duration: 251.95456ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:50:38.472532Z","caller":"traceutil/trace.go:171","msg":"trace[2006789825] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"472.043727ms","start":"2026-05-26T15:50:38.000463Z","end":"2026-05-26T15:50:38.472506Z","steps":["trace[2006789825] 'process raft request' (duration: 471.834504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:50:38.473049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:50:38.000431Z","time spent":"472.553485ms","remote":"127.0.0.1:45000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:50:38.473246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.132857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-05-26T15:50:38.473281Z","caller":"traceutil/trace.go:171","msg":"trace[1649628787] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:642; }","duration":"468.207278ms","start":"2026-05-26T15:50:38.005064Z","end":"2026-05-26T15:50:38.473272Z","steps":["trace[1649628787] 'agreement among raft nodes before linearized reading' (duration: 468.079196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:50:38.473304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:50:38.005055Z","time spent":"468.243318ms","remote":"127.0.0.1:45000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":98735,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-05-26T15:50:38.940469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.8294ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:50:38.94052Z","caller":"traceutil/trace.go:171","msg":"trace[1404947330] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"281.137163ms","start":"2026-05-26T15:50:38.659367Z","end":"2026-05-26T15:50:38.940504Z","steps":["trace[1404947330] 'read index received' (duration: 276.896098ms)","trace[1404947330] 'applied index is now lower than readState.Index' (duration: 4.240315ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:50:38.940605Z","caller":"traceutil/trace.go:171","msg":"trace[1072017548] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:653; }","duration":"176.990233ms","start":"2026-05-26T15:50:38.763598Z","end":"2026-05-26T15:50:38.940588Z","steps":["trace[1072017548] 'range keys from in-memory index tree' (duration: 176.80861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:50:38.940622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.239944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-05-26T15:50:38.940549Z","caller":"traceutil/trace.go:171","msg":"trace[1128449431] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"338.409701ms","start":"2026-05-26T15:50:38.602125Z","end":"2026-05-26T15:50:38.940535Z","steps":["trace[1128449431] 'process raft request' (duration: 334.171677ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:50:38.940654Z","caller":"traceutil/trace.go:171","msg":"trace[652541463] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:654; }","duration":"281.310626ms","start":"2026-05-26T15:50:38.659335Z","end":"2026-05-26T15:50:38.940646Z","steps":["trace[652541463] 'agreement among raft nodes before linearized reading' (duration: 281.188424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:50:38.940719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:50:38.602105Z","time spent":"338.564493ms","remote":"127.0.0.1:45000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:50:38.943696Z","caller":"traceutil/trace.go:171","msg":"trace[1948670187] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"157.383546ms","start":"2026-05-26T15:50:38.786287Z","end":"2026-05-26T15:50:38.94367Z","steps":["trace[1948670187] 'process raft request' (duration: 157.266915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:50:45.620436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.988986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-26T15:50:45.620618Z","caller":"traceutil/trace.go:171","msg":"trace[621265299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:710; }","duration":"173.215238ms","start":"2026-05-26T15:50:45.447375Z","end":"2026-05-26T15:50:45.620591Z","steps":["trace[621265299] 'range keys from in-memory index tree' (duration: 172.760343ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:50:45.922893Z","caller":"traceutil/trace.go:171","msg":"trace[1511298332] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"298.273358ms","start":"2026-05-26T15:50:45.62459Z","end":"2026-05-26T15:50:45.922863Z","steps":["trace[1511298332] 'process raft request' (duration: 298.124506ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:50:46.057305Z","caller":"traceutil/trace.go:171","msg":"trace[1160951983] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"423.645776ms","start":"2026-05-26T15:50:45.633627Z","end":"2026-05-26T15:50:46.057273Z","steps":["trace[1160951983] 'process raft request' (duration: 397.828779ms)","trace[1160951983] 'compare' (duration: 25.616495ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:50:46.05749Z","caller":"traceutil/trace.go:171","msg":"trace[387513705] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:733; }","duration":"327.703433ms","start":"2026-05-26T15:50:45.729765Z","end":"2026-05-26T15:50:46.057468Z","steps":["trace[387513705] 'read index received' (duration: 193.070442ms)","trace[387513705] 'applied index is now lower than readState.Index' (duration: 134.632071ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:50:46.057621Z","caller":"traceutil/trace.go:171","msg":"trace[1802384231] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"275.74102ms","start":"2026-05-26T15:50:45.781854Z","end":"2026-05-26T15:50:46.057595Z","steps":["trace[1802384231] 'process raft request' (duration: 275.371025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:50:46.057691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.923477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-26T15:50:46.057725Z","caller":"traceutil/trace.go:171","msg":"trace[176350719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:713; }","duration":"327.999417ms","start":"2026-05-26T15:50:45.729715Z","end":"2026-05-26T15:50:46.057715Z","steps":["trace[176350719] 'agreement among raft nodes before linearized reading' (duration: 327.819745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:50:46.057755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:50:45.729694Z","time spent":"328.054688ms","remote":"127.0.0.1:45232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T15:50:46.057467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:50:45.633593Z","time spent":"423.792039ms","remote":"127.0.0.1:45154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:50:48.501387Z","caller":"traceutil/trace.go:171","msg":"trace[1039606272] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"428.61441ms","start":"2026-05-26T15:50:48.072738Z","end":"2026-05-26T15:50:48.501353Z","steps":["trace[1039606272] 'read index received' (duration: 428.450868ms)","trace[1039606272] 'applied index is now lower than readState.Index' (duration: 162.012µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:50:48.501422Z","caller":"traceutil/trace.go:171","msg":"trace[1015910814] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"569.977994ms","start":"2026-05-26T15:50:47.931413Z","end":"2026-05-26T15:50:48.501391Z","steps":["trace[1015910814] 'process raft request' (duration: 569.75861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:50:48.501653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.894175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-26T15:50:48.501709Z","caller":"traceutil/trace.go:171","msg":"trace[1712125385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"428.995206ms","start":"2026-05-26T15:50:48.072695Z","end":"2026-05-26T15:50:48.501691Z","steps":["trace[1712125385] 'agreement among raft nodes before linearized reading' (duration: 428.774143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:50:48.501768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:50:47.931388Z","time spent":"570.224038ms","remote":"127.0.0.1:45232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T15:50:48.501777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:50:48.072674Z","time spent":"429.091717ms","remote":"127.0.0.1:45232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-26T15:50:52.904865Z","caller":"traceutil/trace.go:171","msg":"trace[981590733] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"159.535771ms","start":"2026-05-26T15:50:52.745298Z","end":"2026-05-26T15:50:52.904834Z","steps":["trace[981590733] 'process raft request' (duration: 157.675755ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:51:00.602534Z","caller":"traceutil/trace.go:171","msg":"trace[968456198] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"341.244477ms","start":"2026-05-26T15:51:00.261267Z","end":"2026-05-26T15:51:00.602512Z","steps":["trace[968456198] 'process raft request' (duration: 341.096335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:51:00.602669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:51:00.261242Z","time spent":"341.362138ms","remote":"127.0.0.1:45038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":838,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T15:51:00.883471Z","caller":"traceutil/trace.go:171","msg":"trace[958813326] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"183.29366ms","start":"2026-05-26T15:51:00.700147Z","end":"2026-05-26T15:51:00.88344Z","steps":["trace[958813326] 'process raft request' (duration: 182.529301ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:51:02.826585Z","caller":"traceutil/trace.go:171","msg":"trace[673473299] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"200.89496ms","start":"2026-05-26T15:51:02.625667Z","end":"2026-05-26T15:51:02.826562Z","steps":["trace[673473299] 'read index received' (duration: 200.579996ms)","trace[673473299] 'applied index is now lower than readState.Index' (duration: 313.944µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:51:02.826733Z","caller":"traceutil/trace.go:171","msg":"trace[193402634] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"203.871877ms","start":"2026-05-26T15:51:02.622813Z","end":"2026-05-26T15:51:02.826685Z","steps":["trace[193402634] 'process raft request' (duration: 203.577624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:51:02.826859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.173543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-26T15:51:02.826896Z","caller":"traceutil/trace.go:171","msg":"trace[1141187474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:819; }","duration":"201.257364ms","start":"2026-05-26T15:51:02.625627Z","end":"2026-05-26T15:51:02.826885Z","steps":["trace[1141187474] 'agreement among raft nodes before linearized reading' (duration: 201.073312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:51:02.827054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.740556ms","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-05-26T15:51:02.827202Z","caller":"traceutil/trace.go:171","msg":"trace[163093669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:819; }","duration":"126.900617ms","start":"2026-05-26T15:51:02.700245Z","end":"2026-05-26T15:51:02.827145Z","steps":["trace[163093669] 'agreement among raft nodes before linearized reading' (duration: 126.467512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:51:06.340138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.836514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:51:06.340216Z","caller":"traceutil/trace.go:171","msg":"trace[395013030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:837; }","duration":"768.940515ms","start":"2026-05-26T15:51:05.571255Z","end":"2026-05-26T15:51:06.340196Z","steps":["trace[395013030] 'range keys from in-memory index tree' (duration: 768.776843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:51:06.340249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:51:05.57124Z","time spent":"769.001085ms","remote":"127.0.0.1:44936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T15:51:06.343521Z","caller":"traceutil/trace.go:171","msg":"trace[1036425903] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"647.899002ms","start":"2026-05-26T15:51:05.695603Z","end":"2026-05-26T15:51:06.343502Z","steps":["trace[1036425903] 'process raft request' (duration: 647.783671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:51:06.343642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:51:05.695579Z","time spent":"648.000513ms","remote":"127.0.0.1:45208","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:51:14.904615Z","caller":"traceutil/trace.go:171","msg":"trace[157450163] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"222.00193ms","start":"2026-05-26T15:51:14.682588Z","end":"2026-05-26T15:51:14.90459Z","steps":["trace[157450163] 'process raft request' (duration: 221.842649ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:51:14.935993Z","caller":"traceutil/trace.go:171","msg":"trace[1387621707] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"151.878767ms","start":"2026-05-26T15:51:14.78408Z","end":"2026-05-26T15:51:14.935959Z","steps":["trace[1387621707] 'process raft request' (duration: 151.651994ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:52:57.795625Z","caller":"traceutil/trace.go:171","msg":"trace[1299812370] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"190.048112ms","start":"2026-05-26T15:52:57.605558Z","end":"2026-05-26T15:52:57.795606Z","steps":["trace[1299812370] 'process raft request' (duration: 189.986832ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:52:57.795668Z","caller":"traceutil/trace.go:171","msg":"trace[1697259977] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"190.685419ms","start":"2026-05-26T15:52:57.604938Z","end":"2026-05-26T15:52:57.795623Z","steps":["trace[1697259977] 'process raft request' (duration: 182.760166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:52:58.418102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.149847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-26T15:52:58.418179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.281534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T15:52:58.418218Z","caller":"traceutil/trace.go:171","msg":"trace[1547219445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1375; }","duration":"463.332087ms","start":"2026-05-26T15:52:57.954861Z","end":"2026-05-26T15:52:58.418193Z","steps":["trace[1547219445] 'range keys from in-memory index tree' (duration: 462.935725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:52:58.418276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:52:57.954833Z","time spent":"463.431268ms","remote":"127.0.0.1:45232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-26T15:52:58.418305Z","caller":"traceutil/trace.go:171","msg":"trace[285811926] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1375; }","duration":"455.404535ms","start":"2026-05-26T15:52:57.962836Z","end":"2026-05-26T15:52:58.418241Z","steps":["trace[285811926] 'count revisions from in-memory index tree' (duration: 455.219453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:52:58.418385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:52:57.962824Z","time spent":"455.516815ms","remote":"127.0.0.1:55702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "}