{"level":"warn","ts":"2026-08-26T00:16:43.064019Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:16:43.064101Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.152: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://162.253.55.152:2380","--initial-cluster=instance=https://162.253.55.152:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.152:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.152: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-08-26T00:16:43.06417Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:16:43.064178Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.152:2380"]} {"level":"info","ts":"2026-08-26T00:16:43.064196Z","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-08-26T00:16:43.064829Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.152:2379"]} {"level":"info","ts":"2026-08-26T00:16:43.064896Z","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://162.253.55.152:2380"],"listen-peer-urls":["https://162.253.55.152:2380"],"advertise-client-urls":["https://162.253.55.152:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.152:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.152: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-08-26T00:16:43.074457Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.349916ms"} {"level":"info","ts":"2026-08-26T00:16:43.089281Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3211802a8f385ce6","cluster-id":"25d088d9a9519f25"} {"level":"info","ts":"2026-08-26T00:16:43.089359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 switched to configuration voters=()"} {"level":"info","ts":"2026-08-26T00:16:43.089379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 became follower at term 0"} {"level":"info","ts":"2026-08-26T00:16:43.089398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3211802a8f385ce6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-26T00:16:43.089409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 became follower at term 1"} {"level":"info","ts":"2026-08-26T00:16:43.089466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 switched to configuration voters=(3607805696780295398)"} {"level":"warn","ts":"2026-08-26T00:16:43.095639Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-26T00:16:43.098357Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-26T00:16:43.099645Z","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-08-26T00:16:43.102058Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3211802a8f385ce6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-26T00:16:43.102877Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3211802a8f385ce6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-26T00:16:43.10433Z","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-08-26T00:16:43.104503Z","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-08-26T00:16:43.104544Z","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-08-26T00:16:43.106443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 switched to configuration voters=(3607805696780295398)"} {"level":"info","ts":"2026-08-26T00:16:43.106621Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25d088d9a9519f25","local-member-id":"3211802a8f385ce6","added-peer-id":"3211802a8f385ce6","added-peer-peer-urls":["https://162.253.55.152:2380"]} {"level":"info","ts":"2026-08-26T00:16:43.107638Z","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-08-26T00:16:43.107861Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3211802a8f385ce6","initial-advertise-peer-urls":["https://162.253.55.152:2380"],"listen-peer-urls":["https://162.253.55.152:2380"],"advertise-client-urls":["https://162.253.55.152:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.152:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-26T00:16:43.107907Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-26T00:16:43.107958Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.152:2380"} {"level":"info","ts":"2026-08-26T00:16:43.107968Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.152:2380"} {"level":"info","ts":"2026-08-26T00:16:43.389878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 is starting a new election at term 1"} {"level":"info","ts":"2026-08-26T00:16:43.389934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-26T00:16:43.389997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 received MsgPreVoteResp from 3211802a8f385ce6 at term 1"} {"level":"info","ts":"2026-08-26T00:16:43.390027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 became candidate at term 2"} {"level":"info","ts":"2026-08-26T00:16:43.390043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 received MsgVoteResp from 3211802a8f385ce6 at term 2"} {"level":"info","ts":"2026-08-26T00:16:43.390063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3211802a8f385ce6 became leader at term 2"} {"level":"info","ts":"2026-08-26T00:16:43.390082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3211802a8f385ce6 elected leader 3211802a8f385ce6 at term 2"} {"level":"info","ts":"2026-08-26T00:16:43.392434Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3211802a8f385ce6","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.152:2379]}","request-path":"/0/members/3211802a8f385ce6/attributes","cluster-id":"25d088d9a9519f25","publish-timeout":"7s"} {"level":"info","ts":"2026-08-26T00:16:43.392798Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:16:43.39276Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:16:43.393077Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-26T00:16:43.393177Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-26T00:16:43.393075Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:16:43.394057Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25d088d9a9519f25","local-member-id":"3211802a8f385ce6","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:16:43.394187Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:16:43.39423Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:16:43.401538Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.152:2379"} {"level":"info","ts":"2026-08-26T00:16:43.402534Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-26T00:16:53.532424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.417104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:16:53.532574Z","caller":"traceutil/trace.go:171","msg":"trace[1536345189] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:356; }","duration":"112.846343ms","start":"2026-08-26T00:16:53.419712Z","end":"2026-08-26T00:16:53.532558Z","steps":["trace[1536345189] 'read index received' (duration: 44.961µs)","trace[1536345189] 'applied index is now lower than readState.Index' (duration: 112.800232ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:53.532648Z","caller":"traceutil/trace.go:171","msg":"trace[2004111532] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"124.949954ms","start":"2026-08-26T00:16:53.407645Z","end":"2026-08-26T00:16:53.532595Z","steps":["trace[2004111532] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:416; } (duration: 123.260782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:53.532837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.949555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-08-26T00:16:53.532905Z","caller":"traceutil/trace.go:171","msg":"trace[783387058] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:344; }","duration":"113.23052ms","start":"2026-08-26T00:16:53.419663Z","end":"2026-08-26T00:16:53.532894Z","steps":["trace[783387058] 'agreement among raft nodes before linearized reading' (duration: 112.955005ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:09.169612Z","caller":"traceutil/trace.go:171","msg":"trace[1231323495] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"513.945832ms","start":"2026-08-26T00:17:08.655635Z","end":"2026-08-26T00:17:09.169581Z","steps":["trace[1231323495] 'process raft request' (duration: 513.719467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:09.169741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:08.65561Z","time spent":"514.067464ms","remote":"127.0.0.1:34264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:18.642052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.027582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:18.642152Z","caller":"traceutil/trace.go:171","msg":"trace[357264516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:446; }","duration":"629.195346ms","start":"2026-08-26T00:17:18.012939Z","end":"2026-08-26T00:17:18.642134Z","steps":["trace[357264516] 'range keys from in-memory index tree' (duration: 628.91873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:18.642197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:18.012913Z","time spent":"629.275198ms","remote":"127.0.0.1:34004","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-08-26T00:17:22.251478Z","caller":"traceutil/trace.go:171","msg":"trace[1441892074] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"432.977414ms","start":"2026-08-26T00:17:21.818474Z","end":"2026-08-26T00:17:22.251452Z","steps":["trace[1441892074] 'process raft request' (duration: 432.833291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:22.251763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:21.818453Z","time spent":"433.111158ms","remote":"127.0.0.1:34264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:17:22.522721Z","caller":"traceutil/trace.go:171","msg":"trace[204691366] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"615.9569ms","start":"2026-08-26T00:17:21.906732Z","end":"2026-08-26T00:17:22.522688Z","steps":["trace[204691366] 'process raft request' (duration: 615.822866ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:22.522729Z","caller":"traceutil/trace.go:171","msg":"trace[1858605785] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"619.7251ms","start":"2026-08-26T00:17:21.902945Z","end":"2026-08-26T00:17:22.52267Z","steps":["trace[1858605785] 'process raft request' (duration: 619.482965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:22.52291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:21.906726Z","time spent":"616.076303ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:17:22.52283Z","caller":"traceutil/trace.go:171","msg":"trace[2047994] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"614.24609ms","start":"2026-08-26T00:17:21.908548Z","end":"2026-08-26T00:17:22.522794Z","steps":["trace[2047994] 'process raft request' (duration: 614.073105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:22.523025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:21.902925Z","time spent":"619.919736ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-26T00:17:22.523252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:21.908521Z","time spent":"614.568708ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:17:22.523426Z","caller":"traceutil/trace.go:171","msg":"trace[1194564150] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"601.854125ms","start":"2026-08-26T00:17:21.921555Z","end":"2026-08-26T00:17:22.523409Z","steps":["trace[1194564150] 'process raft request' (duration: 601.632789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:22.523501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:21.921544Z","time spent":"601.924986ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:17:22.52361Z","caller":"traceutil/trace.go:171","msg":"trace[824576022] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"592.612024ms","start":"2026-08-26T00:17:21.930982Z","end":"2026-08-26T00:17:22.523594Z","steps":["trace[824576022] 'process raft request' (duration: 592.404679ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:22.523735Z","caller":"traceutil/trace.go:171","msg":"trace[336366654] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"596.561098ms","start":"2026-08-26T00:17:21.927152Z","end":"2026-08-26T00:17:22.523713Z","steps":["trace[336366654] 'process raft request' (duration: 596.152268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:22.523693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:21.93096Z","time spent":"592.699036ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-26T00:17:22.523801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:21.927116Z","time spent":"596.653659ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:17:22.5238Z","caller":"traceutil/trace.go:171","msg":"trace[1096036468] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:506; }","duration":"537.130948ms","start":"2026-08-26T00:17:21.98665Z","end":"2026-08-26T00:17:22.523781Z","steps":["trace[1096036468] 'read index received' (duration: 264.769927ms)","trace[1096036468] 'applied index is now lower than readState.Index' (duration: 272.360061ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:22.523886Z","caller":"traceutil/trace.go:171","msg":"trace[2045533690] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"588.905705ms","start":"2026-08-26T00:17:21.934971Z","end":"2026-08-26T00:17:22.523877Z","steps":["trace[2045533690] 'process raft request' (duration: 588.453354ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:22.523916Z","caller":"traceutil/trace.go:171","msg":"trace[1270728265] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"596.68977ms","start":"2026-08-26T00:17:21.927215Z","end":"2026-08-26T00:17:22.523905Z","steps":["trace[1270728265] 'process raft request' (duration: 596.131287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:22.523949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:21.93495Z","time spent":"588.966906ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:17:22.524086Z","caller":"traceutil/trace.go:171","msg":"trace[95120549] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"581.18326ms","start":"2026-08-26T00:17:21.942888Z","end":"2026-08-26T00:17:22.524072Z","steps":["trace[95120549] 'process raft request' (duration: 580.581816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:22.524145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.877288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:22.524186Z","caller":"traceutil/trace.go:171","msg":"trace[685008203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"258.96767ms","start":"2026-08-26T00:17:22.265207Z","end":"2026-08-26T00:17:22.524175Z","steps":["trace[685008203] 'agreement among raft nodes before linearized reading' (duration: 258.836547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:22.524301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:21.94288Z","time spent":"581.261962ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-26T00:17:22.523982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:21.927212Z","time spent":"596.729991ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:17:22.524057Z","caller":"traceutil/trace.go:171","msg":"trace[101003169] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"431.612016ms","start":"2026-08-26T00:17:22.092416Z","end":"2026-08-26T00:17:22.524028Z","steps":["trace[101003169] 'process raft request' (duration: 431.270148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:22.524424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.713752ms","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-08-26T00:17:22.524562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:22.092383Z","time spent":"432.149379ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:17:22.52408Z","caller":"traceutil/trace.go:171","msg":"trace[727954697] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"569.317657ms","start":"2026-08-26T00:17:21.95475Z","end":"2026-08-26T00:17:22.524067Z","steps":["trace[727954697] 'process raft request' (duration: 568.769074ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:22.524623Z","caller":"traceutil/trace.go:171","msg":"trace[1927067466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:500; }","duration":"537.952808ms","start":"2026-08-26T00:17:21.986607Z","end":"2026-08-26T00:17:22.52456Z","steps":["trace[1927067466] 'agreement among raft nodes before linearized reading' (duration: 537.425125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:22.524696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:21.95472Z","time spent":"569.934501ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-26T00:17:22.524713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:21.986596Z","time spent":"538.079701ms","remote":"127.0.0.1:34264","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-08-26T00:17:22.524096Z","caller":"traceutil/trace.go:171","msg":"trace[495999570] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"551.495962ms","start":"2026-08-26T00:17:21.972592Z","end":"2026-08-26T00:17:22.524088Z","steps":["trace[495999570] 'process raft request' (duration: 550.97985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:22.525056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:21.972554Z","time spent":"552.219779ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:17:28.995522Z","caller":"traceutil/trace.go:171","msg":"trace[245557154] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"389.47435ms","start":"2026-08-26T00:17:28.606025Z","end":"2026-08-26T00:17:28.995499Z","steps":["trace[245557154] 'read index received' (duration: 388.737192ms)","trace[245557154] 'applied index is now lower than readState.Index' (duration: 736.278µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:28.995597Z","caller":"traceutil/trace.go:171","msg":"trace[517602035] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"484.029534ms","start":"2026-08-26T00:17:28.511543Z","end":"2026-08-26T00:17:28.995572Z","steps":["trace[517602035] 'process raft request' (duration: 483.415859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:28.995778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.740706ms","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-08-26T00:17:28.995812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:28.511513Z","time spent":"484.178858ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:17:28.995854Z","caller":"traceutil/trace.go:171","msg":"trace[249553174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"389.855859ms","start":"2026-08-26T00:17:28.605983Z","end":"2026-08-26T00:17:28.995838Z","steps":["trace[249553174] 'agreement among raft nodes before linearized reading' (duration: 389.664094ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:28.995907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:28.605965Z","time spent":"389.93013ms","remote":"127.0.0.1:34264","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":"warn","ts":"2026-08-26T00:17:29.310002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.947373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:29.310151Z","caller":"traceutil/trace.go:171","msg":"trace[2051130219] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"297.459384ms","start":"2026-08-26T00:17:29.012668Z","end":"2026-08-26T00:17:29.310128Z","steps":["trace[2051130219] 'read index received' (duration: 87.292446ms)","trace[2051130219] 'applied index is now lower than readState.Index' (duration: 210.165578ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:29.310286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.619368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:29.310332Z","caller":"traceutil/trace.go:171","msg":"trace[1131683190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"297.69609ms","start":"2026-08-26T00:17:29.012621Z","end":"2026-08-26T00:17:29.310317Z","steps":["trace[1131683190] 'agreement among raft nodes before linearized reading' (duration: 297.624188ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:29.310238Z","caller":"traceutil/trace.go:171","msg":"trace[1163676583] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"309.498833ms","start":"2026-08-26T00:17:29.000702Z","end":"2026-08-26T00:17:29.310201Z","steps":["trace[1163676583] 'process raft request' (duration: 99.247723ms)","trace[1163676583] 'compare' (duration: 209.787929ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:29.310685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:29.000661Z","time spent":"309.946065ms","remote":"127.0.0.1:34264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:29.810864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6694214073227302438,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:30.311337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6694214073227302438,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:30.315247Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.215277217s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-26T00:17:30.340569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.02868ms","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-08-26T00:17:30.340743Z","caller":"traceutil/trace.go:171","msg":"trace[1337144621] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:596; }","duration":"615.218224ms","start":"2026-08-26T00:17:29.725501Z","end":"2026-08-26T00:17:30.34072Z","steps":["trace[1337144621] 'range keys from in-memory index tree' (duration: 615.007549ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:30.340786Z","caller":"traceutil/trace.go:171","msg":"trace[58131290] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.320213745s","start":"2026-08-26T00:17:29.020534Z","end":"2026-08-26T00:17:30.340748Z","steps":["trace[58131290] 'process raft request' (duration: 1.294940532s)","trace[58131290] 'compare' (duration: 24.798871ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:30.340799Z","caller":"traceutil/trace.go:171","msg":"trace[211227893] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.286628178s","start":"2026-08-26T00:17:29.054152Z","end":"2026-08-26T00:17:30.34078Z","steps":["trace[211227893] 'process raft request' (duration: 1.286456214s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:30.340936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:29.05411Z","time spent":"1.286747902s","remote":"127.0.0.1:34494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-26T00:17:30.340946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:29.020501Z","time spent":"1.320352759s","remote":"127.0.0.1:34180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:17:30.38836Z","caller":"traceutil/trace.go:171","msg":"trace[267424296] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"431.378239ms","start":"2026-08-26T00:17:29.956954Z","end":"2026-08-26T00:17:30.388332Z","steps":["trace[267424296] 'process raft request' (duration: 431.095312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:30.388494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:29.956924Z","time spent":"431.496521ms","remote":"127.0.0.1:34264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:30.388603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123866617s","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-08-26T00:17:30.388679Z","caller":"traceutil/trace.go:171","msg":"trace[392698610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"1.124022111s","start":"2026-08-26T00:17:29.264646Z","end":"2026-08-26T00:17:30.388668Z","steps":["trace[392698610] 'agreement among raft nodes before linearized reading' (duration: 1.123849917s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:30.38871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:29.264623Z","time spent":"1.124080303s","remote":"127.0.0.1:34264","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-08-26T00:17:30.388967Z","caller":"traceutil/trace.go:171","msg":"trace[796011766] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:622; }","duration":"1.07821571s","start":"2026-08-26T00:17:29.310212Z","end":"2026-08-26T00:17:30.388428Z","steps":["trace[796011766] 'read index received' (duration: 1.005271793s)","trace[796011766] 'applied index is now lower than readState.Index' (duration: 72.942297ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:30.389096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.943456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:30.389171Z","caller":"traceutil/trace.go:171","msg":"trace[383172805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"376.080319ms","start":"2026-08-26T00:17:30.013082Z","end":"2026-08-26T00:17:30.389163Z","steps":["trace[383172805] 'agreement among raft nodes before linearized reading' (duration: 375.983877ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:30.389533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:30.012989Z","time spent":"376.535891ms","remote":"127.0.0.1:34004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:17:30.389762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119219062s","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-08-26T00:17:30.389865Z","caller":"traceutil/trace.go:171","msg":"trace[459424538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"1.119422237s","start":"2026-08-26T00:17:29.270417Z","end":"2026-08-26T00:17:30.389839Z","steps":["trace[459424538] 'agreement among raft nodes before linearized reading' (duration: 1.118048112s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:30.38993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:29.270396Z","time spent":"1.11951859s","remote":"127.0.0.1:34264","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-08-26T00:17:31.516366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6694214073227302442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:32.017034Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6694214073227302442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:32.517844Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6694214073227302442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:32.520559Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.737787756s","expected-duration":"1s"} {"level":"info","ts":"2026-08-26T00:17:32.521674Z","caller":"traceutil/trace.go:171","msg":"trace[1368171020] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.740203906s","start":"2026-08-26T00:17:30.781443Z","end":"2026-08-26T00:17:32.521646Z","steps":["trace[1368171020] 'process raft request' (duration: 1.73955796s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:32.521848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:30.781414Z","time spent":"1.7403449s","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:17:32.547328Z","caller":"traceutil/trace.go:171","msg":"trace[1535091210] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"1.531947805s","start":"2026-08-26T00:17:31.015354Z","end":"2026-08-26T00:17:32.547302Z","steps":["trace[1535091210] 'read index received' (duration: 1.505654231s)","trace[1535091210] 'applied index is now lower than readState.Index' (duration: 26.291824ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:32.547514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.53212986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:32.547612Z","caller":"traceutil/trace.go:171","msg":"trace[971930815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"1.532288233s","start":"2026-08-26T00:17:31.015296Z","end":"2026-08-26T00:17:32.547584Z","steps":["trace[971930815] 'agreement among raft nodes before linearized reading' (duration: 1.532098879s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:32.547672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:31.015274Z","time spent":"1.532387966s","remote":"127.0.0.1:34004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:17:32.547825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231007641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T00:17:32.547893Z","caller":"traceutil/trace.go:171","msg":"trace[1096832590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"1.231190226s","start":"2026-08-26T00:17:31.316688Z","end":"2026-08-26T00:17:32.547878Z","steps":["trace[1096832590] 'agreement among raft nodes before linearized reading' (duration: 1.230840237s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:32.54789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.752807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-26T00:17:32.547947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:31.316663Z","time spent":"1.231275328s","remote":"127.0.0.1:34264","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":"warn","ts":"2026-08-26T00:17:32.547995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.800534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:32.547978Z","caller":"traceutil/trace.go:171","msg":"trace[246754065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"134.917801ms","start":"2026-08-26T00:17:32.41304Z","end":"2026-08-26T00:17:32.547958Z","steps":["trace[246754065] 'agreement among raft nodes before linearized reading' (duration: 134.630484ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:32.548073Z","caller":"traceutil/trace.go:171","msg":"trace[1961164463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"281.938177ms","start":"2026-08-26T00:17:32.266115Z","end":"2026-08-26T00:17:32.548054Z","steps":["trace[1961164463] 'agreement among raft nodes before linearized reading' (duration: 281.797094ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:35.057996Z","caller":"traceutil/trace.go:171","msg":"trace[1696245105] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"126.182315ms","start":"2026-08-26T00:17:34.931783Z","end":"2026-08-26T00:17:35.057965Z","steps":["trace[1696245105] 'read index received' (duration: 35.452574ms)","trace[1696245105] 'applied index is now lower than readState.Index' (duration: 90.728081ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:17:35.058142Z","caller":"traceutil/trace.go:171","msg":"trace[791610151] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"127.278742ms","start":"2026-08-26T00:17:34.930836Z","end":"2026-08-26T00:17:35.058114Z","steps":["trace[791610151] 'process raft request' (duration: 36.332117ms)","trace[791610151] 'compare' (duration: 90.630697ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:35.058226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.41733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:35.058268Z","caller":"traceutil/trace.go:171","msg":"trace[228199737] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:630; }","duration":"126.504353ms","start":"2026-08-26T00:17:34.93175Z","end":"2026-08-26T00:17:35.058254Z","steps":["trace[228199737] 'agreement among raft nodes before linearized reading' (duration: 126.352949ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:35.062556Z","caller":"traceutil/trace.go:171","msg":"trace[1437843897] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"126.669658ms","start":"2026-08-26T00:17:34.935858Z","end":"2026-08-26T00:17:35.062528Z","steps":["trace[1437843897] 'process raft request' (duration: 126.501404ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:35.062627Z","caller":"traceutil/trace.go:171","msg":"trace[1495395453] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"125.864777ms","start":"2026-08-26T00:17:34.936643Z","end":"2026-08-26T00:17:35.062508Z","steps":["trace[1495395453] 'process raft request' (duration: 125.810666ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:35.062629Z","caller":"traceutil/trace.go:171","msg":"trace[1498935964] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"126.488503ms","start":"2026-08-26T00:17:34.93611Z","end":"2026-08-26T00:17:35.062598Z","steps":["trace[1498935964] 'process raft request' (duration: 126.299918ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:35.062618Z","caller":"traceutil/trace.go:171","msg":"trace[1798414755] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"128.392582ms","start":"2026-08-26T00:17:34.934195Z","end":"2026-08-26T00:17:35.062588Z","steps":["trace[1798414755] 'process raft request' (duration: 128.092685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:35.067143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.581464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-6df7j\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-08-26T00:17:35.067371Z","caller":"traceutil/trace.go:171","msg":"trace[1623071507] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-6df7j; range_end:; response_count:1; response_revision:635; }","duration":"102.873442ms","start":"2026-08-26T00:17:34.964487Z","end":"2026-08-26T00:17:35.06736Z","steps":["trace[1623071507] 'agreement among raft nodes before linearized reading' (duration: 102.511942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:49.442842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.290338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:17:49.442848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.576776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T00:17:49.442956Z","caller":"traceutil/trace.go:171","msg":"trace[260196523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"430.424592ms","start":"2026-08-26T00:17:49.012506Z","end":"2026-08-26T00:17:49.442931Z","steps":["trace[260196523] 'range keys from in-memory index tree' (duration: 430.091432ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:49.442983Z","caller":"traceutil/trace.go:171","msg":"trace[1829967527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:766; }","duration":"779.779312ms","start":"2026-08-26T00:17:48.663175Z","end":"2026-08-26T00:17:49.442954Z","steps":["trace[1829967527] 'range keys from in-memory index tree' (duration: 779.243318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:49.44304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:48.663149Z","time spent":"779.880464ms","remote":"127.0.0.1:34264","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":"warn","ts":"2026-08-26T00:17:49.443179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.421531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-26T00:17:49.443165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.01989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-26T00:17:49.443268Z","caller":"traceutil/trace.go:171","msg":"trace[1497835534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"724.547514ms","start":"2026-08-26T00:17:48.7187Z","end":"2026-08-26T00:17:49.443248Z","steps":["trace[1497835534] 'range keys from in-memory index tree' (duration: 723.483925ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:49.443287Z","caller":"traceutil/trace.go:171","msg":"trace[1395179176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"727.142673ms","start":"2026-08-26T00:17:48.716109Z","end":"2026-08-26T00:17:49.443251Z","steps":["trace[1395179176] 'range keys from in-memory index tree' (duration: 726.755164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:49.44332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:48.718685Z","time spent":"724.625016ms","remote":"127.0.0.1:34264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-26T00:17:49.44336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.887814ms","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-08-26T00:17:49.443395Z","caller":"traceutil/trace.go:171","msg":"trace[983160413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"724.995287ms","start":"2026-08-26T00:17:48.718391Z","end":"2026-08-26T00:17:49.443386Z","steps":["trace[983160413] 'range keys from in-memory index tree' (duration: 724.633076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:49.443419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:48.718362Z","time spent":"725.051717ms","remote":"127.0.0.1:34264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-26T00:17:49.443323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:48.716082Z","time spent":"727.232996ms","remote":"127.0.0.1:34264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-26T00:17:49.443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:49.012438Z","time spent":"430.551745ms","remote":"127.0.0.1:34004","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-08-26T00:17:49.448045Z","caller":"traceutil/trace.go:171","msg":"trace[822888843] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"372.821735ms","start":"2026-08-26T00:17:49.075209Z","end":"2026-08-26T00:17:49.448031Z","steps":["trace[822888843] 'process raft request' (duration: 372.709612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:49.448146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:49.075174Z","time spent":"372.911167ms","remote":"127.0.0.1:34246","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-08-26T00:17:49.448394Z","caller":"traceutil/trace.go:171","msg":"trace[351727431] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:799; }","duration":"278.550407ms","start":"2026-08-26T00:17:49.169822Z","end":"2026-08-26T00:17:49.448372Z","steps":["trace[351727431] 'read index received' (duration: 278.443794ms)","trace[351727431] 'applied index is now lower than readState.Index' (duration: 104.783µs)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:49.448589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.775072ms","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-08-26T00:17:49.448629Z","caller":"traceutil/trace.go:171","msg":"trace[889269570] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:767; }","duration":"278.860215ms","start":"2026-08-26T00:17:49.16976Z","end":"2026-08-26T00:17:49.44862Z","steps":["trace[889269570] 'agreement among raft nodes before linearized reading' (duration: 278.761343ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:12.629007Z","caller":"traceutil/trace.go:171","msg":"trace[1094483588] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"172.413417ms","start":"2026-08-26T00:18:12.456569Z","end":"2026-08-26T00:18:12.628983Z","steps":["trace[1094483588] 'process raft request' (duration: 172.244472ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:15.815624Z","caller":"traceutil/trace.go:171","msg":"trace[1580602465] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"170.588485ms","start":"2026-08-26T00:18:15.645Z","end":"2026-08-26T00:18:15.815589Z","steps":["trace[1580602465] 'read index received' (duration: 170.372959ms)","trace[1580602465] 'applied index is now lower than readState.Index' (duration: 214.296µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:18:15.815677Z","caller":"traceutil/trace.go:171","msg":"trace[1418290548] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"195.492735ms","start":"2026-08-26T00:18:15.620141Z","end":"2026-08-26T00:18:15.815634Z","steps":["trace[1418290548] 'process raft request' (duration: 195.225098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:18:15.8159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.820902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-26T00:18:15.8159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.867533ms","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-08-26T00:18:15.815939Z","caller":"traceutil/trace.go:171","msg":"trace[263692189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:980; }","duration":"170.899054ms","start":"2026-08-26T00:18:15.64503Z","end":"2026-08-26T00:18:15.815929Z","steps":["trace[263692189] 'agreement among raft nodes before linearized reading' (duration: 170.75596ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:15.815969Z","caller":"traceutil/trace.go:171","msg":"trace[1812428960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:980; }","duration":"170.979546ms","start":"2026-08-26T00:18:15.644972Z","end":"2026-08-26T00:18:15.815952Z","steps":["trace[1812428960] 'agreement among raft nodes before linearized reading' (duration: 170.74297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:18:15.816124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.865866ms","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":"warn","ts":"2026-08-26T00:18:15.816159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.537722ms","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-08-26T00:18:15.816167Z","caller":"traceutil/trace.go:171","msg":"trace[872238314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:980; }","duration":"139.959129ms","start":"2026-08-26T00:18:15.676196Z","end":"2026-08-26T00:18:15.816155Z","steps":["trace[872238314] 'agreement among raft nodes before linearized reading' (duration: 139.743113ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:15.816191Z","caller":"traceutil/trace.go:171","msg":"trace[1935791001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:980; }","duration":"170.579024ms","start":"2026-08-26T00:18:15.645602Z","end":"2026-08-26T00:18:15.816181Z","steps":["trace[1935791001] 'agreement among raft nodes before linearized reading' (duration: 170.492842ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:34.069921Z","caller":"traceutil/trace.go:171","msg":"trace[1225499136] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"137.301942ms","start":"2026-08-26T00:18:33.932579Z","end":"2026-08-26T00:18:34.069881Z","steps":["trace[1225499136] 'process raft request' (duration: 136.745816ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:34.90076Z","caller":"traceutil/trace.go:171","msg":"trace[1256186551] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"159.435968ms","start":"2026-08-26T00:18:34.741297Z","end":"2026-08-26T00:18:34.900733Z","steps":["trace[1256186551] 'process raft request' (duration: 159.252373ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:18:42.41222Z","caller":"traceutil/trace.go:171","msg":"trace[1613973081] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"235.803839ms","start":"2026-08-26T00:18:42.176362Z","end":"2026-08-26T00:18:42.412165Z","steps":["trace[1613973081] 'process raft request' (duration: 233.200961ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:19:04.378226Z","caller":"traceutil/trace.go:171","msg":"trace[855039639] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"109.592652ms","start":"2026-08-26T00:19:04.268603Z","end":"2026-08-26T00:19:04.378196Z","steps":["trace[855039639] 'process raft request' (duration: 109.174809ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:19:48.775769Z","caller":"traceutil/trace.go:171","msg":"trace[1299607344] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"161.587303ms","start":"2026-08-26T00:19:48.614163Z","end":"2026-08-26T00:19:48.77575Z","steps":["trace[1299607344] 'read index received' (duration: 161.526751ms)","trace[1299607344] 'applied index is now lower than readState.Index' (duration: 59.722µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:19:48.775818Z","caller":"traceutil/trace.go:171","msg":"trace[2010970208] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"263.417373ms","start":"2026-08-26T00:19:48.512381Z","end":"2026-08-26T00:19:48.775798Z","steps":["trace[2010970208] 'process raft request' (duration: 263.249028ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:19:48.775962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.794299ms","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-08-26T00:19:48.775993Z","caller":"traceutil/trace.go:171","msg":"trace[967164765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1327; }","duration":"161.889112ms","start":"2026-08-26T00:19:48.614095Z","end":"2026-08-26T00:19:48.775984Z","steps":["trace[967164765] 'agreement among raft nodes before linearized reading' (duration: 161.757208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:19:48.794497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.867205ms","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-08-26T00:19:48.794611Z","caller":"traceutil/trace.go:171","msg":"trace[692660797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1328; }","duration":"169.9905ms","start":"2026-08-26T00:19:48.624596Z","end":"2026-08-26T00:19:48.794586Z","steps":["trace[692660797] 'agreement among raft nodes before linearized reading' (duration: 169.825884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:19:48.794869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.156741ms","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-08-26T00:19:48.794965Z","caller":"traceutil/trace.go:171","msg":"trace[1020115095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1328; }","duration":"173.302346ms","start":"2026-08-26T00:19:48.621642Z","end":"2026-08-26T00:19:48.794944Z","steps":["trace[1020115095] 'agreement among raft nodes before linearized reading' (duration: 172.870611ms)"],"step_count":1}