{"level":"warn","ts":"2026-03-01T12:49:28.470496Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-01T12:49:28.470588Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110: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.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-01T12:49:28.470659Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-01T12:49:28.470677Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-03-01T12:49:28.4707Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-01T12:49:28.471524Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-03-01T12:49:28.471619Z","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.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-01T12:49:28.476727Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.813264ms"} {"level":"info","ts":"2026-03-01T12:49:28.483681Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-03-01T12:49:28.483764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-01T12:49:28.483792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-03-01T12:49:28.483813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-01T12:49:28.483834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-03-01T12:49:28.483903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-03-01T12:49:28.490763Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-01T12:49:28.495084Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-01T12:49:28.49606Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-01T12:49:28.501893Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-01T12:49:28.502805Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-01T12:49:28.503194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-03-01T12:49:28.503309Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-03-01T12:49:28.503371Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-01T12:49:28.503403Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-01T12:49:28.503418Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-01T12:49:28.50783Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-01T12:49:28.507893Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-03-01T12:49:28.507928Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-03-01T12:49:28.508159Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-01T12:49:28.508209Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-01T12:49:28.685327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-01T12:49:28.685435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-01T12:49:28.685503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-03-01T12:49:28.685565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-03-01T12:49:28.685584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-03-01T12:49:28.685606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-03-01T12:49:28.685629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-03-01T12:49:28.687638Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-03-01T12:49:28.687819Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T12:49:28.688011Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T12:49:28.688378Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T12:49:28.689408Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-01T12:49:28.68946Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-01T12:49:28.690443Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-03-01T12:49:28.691682Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-01T12:49:28.695002Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T12:49:28.695085Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T12:49:28.695109Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T12:49:39.370212Z","caller":"traceutil/trace.go:171","msg":"trace[1151594876] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:318; }","duration":"370.567311ms","start":"2026-03-01T12:49:38.999618Z","end":"2026-03-01T12:49:39.370185Z","steps":["trace[1151594876] 'read index received' (duration: 369.874298ms)","trace[1151594876] 'applied index is now lower than readState.Index' (duration: 692.053µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:49:39.370358Z","caller":"traceutil/trace.go:171","msg":"trace[1033775765] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"405.071871ms","start":"2026-03-01T12:49:38.965275Z","end":"2026-03-01T12:49:39.370347Z","steps":["trace[1033775765] 'process raft request' (duration: 404.269667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:39.370714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.794952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"warn","ts":"2026-03-01T12:49:39.370816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.796099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-01T12:49:39.370862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.805806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-01T12:49:39.370873Z","caller":"traceutil/trace.go:171","msg":"trace[762216943] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:307; }","duration":"158.861949ms","start":"2026-03-01T12:49:39.211993Z","end":"2026-03-01T12:49:39.370855Z","steps":["trace[762216943] 'agreement among raft nodes before linearized reading' (duration: 158.794549ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:49:39.37091Z","caller":"traceutil/trace.go:171","msg":"trace[677901120] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:307; }","duration":"360.863197ms","start":"2026-03-01T12:49:39.010029Z","end":"2026-03-01T12:49:39.370892Z","steps":["trace[677901120] 'agreement among raft nodes before linearized reading' (duration: 360.810397ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:49:39.370883Z","caller":"traceutil/trace.go:171","msg":"trace[132408129] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:307; }","duration":"371.120583ms","start":"2026-03-01T12:49:38.999711Z","end":"2026-03-01T12:49:39.370831Z","steps":["trace[132408129] 'agreement among raft nodes before linearized reading' (duration: 370.883652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:39.370969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:39.010017Z","time spent":"360.941887ms","remote":"127.0.0.1:44834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-03-01T12:49:39.371046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.897226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-03-01T12:49:39.370738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.847498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-01T12:49:39.371087Z","caller":"traceutil/trace.go:171","msg":"trace[1275911718] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:307; }","duration":"209.963476ms","start":"2026-03-01T12:49:39.161111Z","end":"2026-03-01T12:49:39.371075Z","steps":["trace[1275911718] 'agreement among raft nodes before linearized reading' (duration: 209.906836ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:49:39.371095Z","caller":"traceutil/trace.go:171","msg":"trace[598180599] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:307; }","duration":"260.22923ms","start":"2026-03-01T12:49:39.110855Z","end":"2026-03-01T12:49:39.371084Z","steps":["trace[598180599] 'agreement among raft nodes before linearized reading' (duration: 259.781798ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:39.371087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:38.96526Z","time spent":"405.130271ms","remote":"127.0.0.1:44724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:49:39.370731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.502311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-03-01T12:49:39.371179Z","caller":"traceutil/trace.go:171","msg":"trace[1159348027] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:307; }","duration":"370.983653ms","start":"2026-03-01T12:49:39.000182Z","end":"2026-03-01T12:49:39.371166Z","steps":["trace[1159348027] 'agreement among raft nodes before linearized reading' (duration: 370.493211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:39.371197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.203933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"warn","ts":"2026-03-01T12:49:39.371213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:39.000166Z","time spent":"371.038353ms","remote":"127.0.0.1:44810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7326,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-03-01T12:49:39.371221Z","caller":"traceutil/trace.go:171","msg":"trace[2010375013] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:307; }","duration":"371.268095ms","start":"2026-03-01T12:49:38.999945Z","end":"2026-03-01T12:49:39.371213Z","steps":["trace[2010375013] 'agreement among raft nodes before linearized reading' (duration: 371.217604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:39.371148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:38.999705Z","time spent":"371.354045ms","remote":"127.0.0.1:44810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5359,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-03-01T12:49:39.37129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.127104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-03-01T12:49:39.37132Z","caller":"traceutil/trace.go:171","msg":"trace[1566532251] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:307; }","duration":"371.166604ms","start":"2026-03-01T12:49:39.000146Z","end":"2026-03-01T12:49:39.371313Z","steps":["trace[1566532251] 'agreement among raft nodes before linearized reading' (duration: 371.122224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:39.370821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.569098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-01T12:49:39.371344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:39.000141Z","time spent":"371.196994ms","remote":"127.0.0.1:44810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-03-01T12:49:39.371366Z","caller":"traceutil/trace.go:171","msg":"trace[1994890255] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:307; }","duration":"310.11227ms","start":"2026-03-01T12:49:39.061244Z","end":"2026-03-01T12:49:39.371357Z","steps":["trace[1994890255] 'agreement among raft nodes before linearized reading' (duration: 309.541918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:39.371403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:39.0612Z","time spent":"310.19394ms","remote":"127.0.0.1:44834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-03-01T12:49:39.370818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.197144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-03-01T12:49:39.370723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.930587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-01T12:49:39.371519Z","caller":"traceutil/trace.go:171","msg":"trace[1519169335] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:307; }","duration":"371.926627ms","start":"2026-03-01T12:49:38.999583Z","end":"2026-03-01T12:49:39.37151Z","steps":["trace[1519169335] 'agreement among raft nodes before linearized reading' (duration: 371.201324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:39.371553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:38.999562Z","time spent":"371.982277ms","remote":"127.0.0.1:45036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-03-01T12:49:39.371546Z","caller":"traceutil/trace.go:171","msg":"trace[545108857] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:307; }","duration":"109.782511ms","start":"2026-03-01T12:49:39.261725Z","end":"2026-03-01T12:49:39.371508Z","steps":["trace[545108857] 'agreement among raft nodes before linearized reading' (duration: 108.912647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:39.371248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:38.999917Z","time spent":"371.323745ms","remote":"127.0.0.1:44810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-03-01T12:49:39.371701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.741437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"info","ts":"2026-03-01T12:49:39.371843Z","caller":"traceutil/trace.go:171","msg":"trace[1933159582] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:307; }","duration":"371.801377ms","start":"2026-03-01T12:49:38.999923Z","end":"2026-03-01T12:49:39.371724Z","steps":["trace[1933159582] 'agreement among raft nodes before linearized reading' (duration: 371.731457ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:39.371888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:38.999903Z","time spent":"371.973828ms","remote":"127.0.0.1:44810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4686,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-03-01T12:49:45.740133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.350013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:49:45.740254Z","caller":"traceutil/trace.go:171","msg":"trace[1046661069] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"1.080425457s","start":"2026-03-01T12:49:44.659815Z","end":"2026-03-01T12:49:45.740241Z","steps":["trace[1046661069] 'process raft request' (duration: 369.768702ms)","trace[1046661069] 'compare' (duration: 709.492706ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:49:45.740297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:44.659787Z","time spent":"1.080493598s","remote":"127.0.0.1:45130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T12:49:46.379247Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644767962343258,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T12:49:46.462348Z","caller":"traceutil/trace.go:171","msg":"trace[906903733] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"718.487304ms","start":"2026-03-01T12:49:45.743837Z","end":"2026-03-01T12:49:46.462324Z","steps":["trace[906903733] 'process raft request' (duration: 718.340923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:46.462493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:45.74381Z","time spent":"718.617025ms","remote":"127.0.0.1:44686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-01T12:49:46.470293Z","caller":"traceutil/trace.go:171","msg":"trace[319657010] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:388; }","duration":"591.556463ms","start":"2026-03-01T12:49:45.878718Z","end":"2026-03-01T12:49:46.470275Z","steps":["trace[319657010] 'read index received' (duration: 583.480557ms)","trace[319657010] 'applied index is now lower than readState.Index' (duration: 8.075306ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:49:46.470473Z","caller":"traceutil/trace.go:171","msg":"trace[1084598603] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"726.431439ms","start":"2026-03-01T12:49:45.744032Z","end":"2026-03-01T12:49:46.470464Z","steps":["trace[1084598603] 'process raft request' (duration: 726.104266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:46.470553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:45.744009Z","time spent":"726.49216ms","remote":"127.0.0.1:44810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-01T12:49:46.47079Z","caller":"traceutil/trace.go:171","msg":"trace[92631081] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"724.495505ms","start":"2026-03-01T12:49:45.746284Z","end":"2026-03-01T12:49:46.470779Z","steps":["trace[92631081] 'process raft request' (duration: 723.94749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:46.470846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.121099ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:49:46.470949Z","caller":"traceutil/trace.go:171","msg":"trace[1153483470] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:376; }","duration":"592.200159ms","start":"2026-03-01T12:49:45.878711Z","end":"2026-03-01T12:49:46.470911Z","steps":["trace[1153483470] 'agreement among raft nodes before linearized reading' (duration: 592.104778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:46.471152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.604151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:49:46.471186Z","caller":"traceutil/trace.go:171","msg":"trace[3432407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"500.673062ms","start":"2026-03-01T12:49:45.970504Z","end":"2026-03-01T12:49:46.471177Z","steps":["trace[3432407] 'agreement among raft nodes before linearized reading' (duration: 500.619892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:46.471211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:45.970482Z","time spent":"500.722833ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-01T12:49:46.471479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.222794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-01T12:49:46.471559Z","caller":"traceutil/trace.go:171","msg":"trace[1140719831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:376; }","duration":"141.327224ms","start":"2026-03-01T12:49:46.330222Z","end":"2026-03-01T12:49:46.47155Z","steps":["trace[1140719831] 'agreement among raft nodes before linearized reading' (duration: 141.224593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:46.470857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:45.746272Z","time spent":"724.551746ms","remote":"127.0.0.1:45104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:49:46.471704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.988607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-01T12:49:46.471743Z","caller":"traceutil/trace.go:171","msg":"trace[1325575365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:376; }","duration":"143.057718ms","start":"2026-03-01T12:49:46.32867Z","end":"2026-03-01T12:49:46.471728Z","steps":["trace[1325575365] 'agreement among raft nodes before linearized reading' (duration: 143.003687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:46.47188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.647584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-01T12:49:46.471915Z","caller":"traceutil/trace.go:171","msg":"trace[1166150102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"266.718954ms","start":"2026-03-01T12:49:46.205188Z","end":"2026-03-01T12:49:46.471907Z","steps":["trace[1166150102] 'agreement among raft nodes before linearized reading' (duration: 266.659254ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:49:54.167399Z","caller":"traceutil/trace.go:171","msg":"trace[573763678] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"173.296696ms","start":"2026-03-01T12:49:53.994077Z","end":"2026-03-01T12:49:54.167374Z","steps":["trace[573763678] 'process raft request' (duration: 173.127514ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:49:58.939865Z","caller":"traceutil/trace.go:171","msg":"trace[845695782] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"304.655729ms","start":"2026-03-01T12:49:58.63518Z","end":"2026-03-01T12:49:58.939836Z","steps":["trace[845695782] 'process raft request' (duration: 304.507667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:49:58.940082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:49:58.635154Z","time spent":"304.796841ms","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:49:58.941202Z","caller":"traceutil/trace.go:171","msg":"trace[1624373668] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:483; }","duration":"266.866251ms","start":"2026-03-01T12:49:58.67432Z","end":"2026-03-01T12:49:58.941186Z","steps":["trace[1624373668] 'read index received' (duration: 265.357861ms)","trace[1624373668] 'applied index is now lower than readState.Index' (duration: 1.50737ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:49:58.941314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.992603ms","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-03-01T12:49:58.941368Z","caller":"traceutil/trace.go:171","msg":"trace[1974159028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:467; }","duration":"267.050233ms","start":"2026-03-01T12:49:58.674307Z","end":"2026-03-01T12:49:58.941357Z","steps":["trace[1974159028] 'agreement among raft nodes before linearized reading' (duration: 266.946212ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:49:58.941636Z","caller":"traceutil/trace.go:171","msg":"trace[643204558] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"265.299349ms","start":"2026-03-01T12:49:58.676301Z","end":"2026-03-01T12:49:58.941601Z","steps":["trace[643204558] 'process raft request' (duration: 264.851234ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:49:58.941942Z","caller":"traceutil/trace.go:171","msg":"trace[461529815] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"297.370901ms","start":"2026-03-01T12:49:58.644485Z","end":"2026-03-01T12:49:58.941855Z","steps":["trace[461529815] 'process raft request' (duration: 296.596771ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:50:05.445136Z","caller":"traceutil/trace.go:171","msg":"trace[1388009017] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"445.38352ms","start":"2026-03-01T12:50:04.999733Z","end":"2026-03-01T12:50:05.445117Z","steps":["trace[1388009017] 'process raft request' (duration: 445.261998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:05.445315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:04.999717Z","time spent":"445.493732ms","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:50:05.445761Z","caller":"traceutil/trace.go:171","msg":"trace[233733893] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:526; }","duration":"443.360638ms","start":"2026-03-01T12:50:05.002388Z","end":"2026-03-01T12:50:05.445748Z","steps":["trace[233733893] 'read index received' (duration: 443.343328ms)","trace[233733893] 'applied index is now lower than readState.Index' (duration: 15.97µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:50:05.445937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.545631ms","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-03-01T12:50:05.445971Z","caller":"traceutil/trace.go:171","msg":"trace[733115281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:506; }","duration":"443.606212ms","start":"2026-03-01T12:50:05.002358Z","end":"2026-03-01T12:50:05.445964Z","steps":["trace[733115281] 'agreement among raft nodes before linearized reading' (duration: 443.438719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:05.445992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:05.002342Z","time spent":"443.645222ms","remote":"127.0.0.1:44904","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-03-01T12:50:05.82373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.60279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:50:05.824061Z","caller":"traceutil/trace.go:171","msg":"trace[1131445179] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"821.579516ms","start":"2026-03-01T12:50:05.002445Z","end":"2026-03-01T12:50:05.824024Z","steps":["trace[1131445179] 'process raft request' (duration: 556.568959ms)","trace[1131445179] 'compare' (duration: 264.267425ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:50:05.824233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.598506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-27krs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-01T12:50:05.824276Z","caller":"traceutil/trace.go:171","msg":"trace[24055911] range","detail":"{range_begin:/registry/pods/kube-system/cilium-27krs; range_end:; response_count:1; response_revision:509; }","duration":"355.682737ms","start":"2026-03-01T12:50:05.468584Z","end":"2026-03-01T12:50:05.824267Z","steps":["trace[24055911] 'agreement among raft nodes before linearized reading' (duration: 355.528724ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:50:05.824295Z","caller":"traceutil/trace.go:171","msg":"trace[1818302648] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"820.619391ms","start":"2026-03-01T12:50:05.003666Z","end":"2026-03-01T12:50:05.824286Z","steps":["trace[1818302648] 'process raft request' (duration: 820.199294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:05.824317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:05.468565Z","time spent":"355.743398ms","remote":"127.0.0.1:44810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-27krs\" "} {"level":"warn","ts":"2026-03-01T12:50:05.82435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:05.003655Z","time spent":"820.669432ms","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:50:05.824397Z","caller":"traceutil/trace.go:171","msg":"trace[113098669] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"375.87645ms","start":"2026-03-01T12:50:05.448494Z","end":"2026-03-01T12:50:05.82437Z","steps":["trace[113098669] 'process raft request' (duration: 375.518475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:05.824229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:05.002438Z","time spent":"821.704638ms","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:50:05.824546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:05.448476Z","time spent":"375.990812ms","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":488,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:50:05.824078Z","caller":"traceutil/trace.go:171","msg":"trace[2094150407] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:526; }","duration":"355.431323ms","start":"2026-03-01T12:50:05.46863Z","end":"2026-03-01T12:50:05.824061Z","steps":["trace[2094150407] 'read index received' (duration: 90.398336ms)","trace[2094150407] 'applied index is now lower than readState.Index' (duration: 265.032147ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:50:06.470871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644767962343515,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T12:50:06.540366Z","caller":"traceutil/trace.go:171","msg":"trace[1000029289] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"569.750835ms","start":"2026-03-01T12:50:05.970593Z","end":"2026-03-01T12:50:06.540344Z","steps":["trace[1000029289] 'read index received' (duration: 569.606533ms)","trace[1000029289] 'applied index is now lower than readState.Index' (duration: 143.192µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:50:06.540512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.894467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:50:06.540486Z","caller":"traceutil/trace.go:171","msg":"trace[559574946] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"688.633586ms","start":"2026-03-01T12:50:05.851821Z","end":"2026-03-01T12:50:06.540455Z","steps":["trace[559574946] 'process raft request' (duration: 688.380522ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:50:06.540546Z","caller":"traceutil/trace.go:171","msg":"trace[1426978130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"569.948068ms","start":"2026-03-01T12:50:05.970586Z","end":"2026-03-01T12:50:06.540534Z","steps":["trace[1426978130] 'agreement among raft nodes before linearized reading' (duration: 569.850647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:06.540585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:05.970544Z","time spent":"570.03309ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-01T12:50:06.540683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:05.851792Z","time spent":"688.789328ms","remote":"127.0.0.1:44810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:50:07.451875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.139156ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-01T12:50:08.77238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.773225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-01T12:50:08.772485Z","caller":"traceutil/trace.go:171","msg":"trace[1423055270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"942.909487ms","start":"2026-03-01T12:50:07.829547Z","end":"2026-03-01T12:50:08.772456Z","steps":["trace[1423055270] 'range keys from in-memory index tree' (duration: 942.569652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:08.772533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:07.829535Z","time spent":"942.986889ms","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-01T12:50:08.772619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.925337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-01T12:50:08.772699Z","caller":"traceutil/trace.go:171","msg":"trace[1673699688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"943.07116ms","start":"2026-03-01T12:50:07.829607Z","end":"2026-03-01T12:50:08.772678Z","steps":["trace[1673699688] 'range keys from in-memory index tree' (duration: 942.740405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:08.772744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:07.829586Z","time spent":"943.150181ms","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-01T12:50:08.772736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.365941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:503"} {"level":"info","ts":"2026-03-01T12:50:08.772805Z","caller":"traceutil/trace.go:171","msg":"trace[1483429911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"944.484833ms","start":"2026-03-01T12:50:07.828303Z","end":"2026-03-01T12:50:08.772787Z","steps":["trace[1483429911] 'range keys from in-memory index tree' (duration: 944.174508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:08.772854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:07.82828Z","time spent":"944.565564ms","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-01T12:50:08.77308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.143284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:50:08.773146Z","caller":"traceutil/trace.go:171","msg":"trace[1236222765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"804.244295ms","start":"2026-03-01T12:50:07.968889Z","end":"2026-03-01T12:50:08.773133Z","steps":["trace[1236222765] 'range keys from in-memory index tree' (duration: 802.866782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:08.773177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:07.968869Z","time spent":"804.300576ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-01T12:50:09.471257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644767962343526,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T12:50:09.505195Z","caller":"traceutil/trace.go:171","msg":"trace[1448838998] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"728.042816ms","start":"2026-03-01T12:50:08.77713Z","end":"2026-03-01T12:50:09.505172Z","steps":["trace[1448838998] 'process raft request' (duration: 727.892104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:09.505415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:08.777114Z","time spent":"728.132308ms","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:50:09.592117Z","caller":"traceutil/trace.go:171","msg":"trace[514468428] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"814.647187ms","start":"2026-03-01T12:50:08.777414Z","end":"2026-03-01T12:50:09.592062Z","steps":["trace[514468428] 'process raft request' (duration: 813.195813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:09.592456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:08.777395Z","time spent":"814.923112ms","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:50:09.594899Z","caller":"traceutil/trace.go:171","msg":"trace[1303689930] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:532; }","duration":"624.321422ms","start":"2026-03-01T12:50:08.970531Z","end":"2026-03-01T12:50:09.594853Z","steps":["trace[1303689930] 'read index received' (duration: 534.554796ms)","trace[1303689930] 'applied index is now lower than readState.Index' (duration: 89.764716ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:50:09.594917Z","caller":"traceutil/trace.go:171","msg":"trace[2010209666] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"816.814314ms","start":"2026-03-01T12:50:08.778068Z","end":"2026-03-01T12:50:09.594882Z","steps":["trace[2010209666] 'process raft request' (duration: 813.606329ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:09.595122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:08.77806Z","time spent":"816.967287ms","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:50:09.595356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.740749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:50:09.5955Z","caller":"traceutil/trace.go:171","msg":"trace[712623204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"625.005934ms","start":"2026-03-01T12:50:08.970474Z","end":"2026-03-01T12:50:09.59548Z","steps":["trace[712623204] 'agreement among raft nodes before linearized reading' (duration: 624.520745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:09.596039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:50:08.970447Z","time spent":"625.577344ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-01T12:50:11.124782Z","caller":"traceutil/trace.go:171","msg":"trace[751187487] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"153.64227ms","start":"2026-03-01T12:50:10.971117Z","end":"2026-03-01T12:50:11.124759Z","steps":["trace[751187487] 'read index received' (duration: 143.0317ms)","trace[751187487] 'applied index is now lower than readState.Index' (duration: 10.60948ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:50:11.124886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.758903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:50:11.124912Z","caller":"traceutil/trace.go:171","msg":"trace[889013530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"153.827925ms","start":"2026-03-01T12:50:10.971074Z","end":"2026-03-01T12:50:11.124902Z","steps":["trace[889013530] 'agreement among raft nodes before linearized reading' (duration: 153.767383ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:50:11.124916Z","caller":"traceutil/trace.go:171","msg":"trace[876963014] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"275.054675ms","start":"2026-03-01T12:50:10.849804Z","end":"2026-03-01T12:50:11.124859Z","steps":["trace[876963014] 'process raft request' (duration: 264.295252ms)","trace[876963014] 'compare' (duration: 10.563709ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:50:14.870164Z","caller":"traceutil/trace.go:171","msg":"trace[292758230] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"163.769865ms","start":"2026-03-01T12:50:14.706364Z","end":"2026-03-01T12:50:14.870134Z","steps":["trace[292758230] 'process raft request' (duration: 161.785298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:50:29.907467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.964094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:50:29.907664Z","caller":"traceutil/trace.go:171","msg":"trace[2105797070] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"219.823589ms","start":"2026-03-01T12:50:29.687822Z","end":"2026-03-01T12:50:29.907645Z","steps":["trace[2105797070] 'process raft request' (duration: 219.765378ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:50:29.907885Z","caller":"traceutil/trace.go:171","msg":"trace[484964328] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"220.053544ms","start":"2026-03-01T12:50:29.687819Z","end":"2026-03-01T12:50:29.907872Z","steps":["trace[484964328] 'process raft request' (duration: 43.603319ms)","trace[484964328] 'compare' (duration: 175.864442ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:51:30.370373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.287581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:51:30.370772Z","caller":"traceutil/trace.go:171","msg":"trace[833410080] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"145.165681ms","start":"2026-03-01T12:51:30.225594Z","end":"2026-03-01T12:51:30.370759Z","steps":["trace[833410080] 'read index received' (duration: 31.431µs)","trace[833410080] 'applied index is now lower than readState.Index' (duration: 145.13335ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:51:30.370915Z","caller":"traceutil/trace.go:171","msg":"trace[123579773] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"404.146378ms","start":"2026-03-01T12:51:29.966695Z","end":"2026-03-01T12:51:30.370841Z","steps":["trace[123579773] 'process raft request' (duration: 224.312749ms)","trace[123579773] 'compare' (duration: 179.158117ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:51:30.371202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:51:29.966682Z","time spent":"404.384476ms","remote":"127.0.0.1:44904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:51:30.371468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.882663ms","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-03-01T12:51:30.371527Z","caller":"traceutil/trace.go:171","msg":"trace[264049804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"145.982396ms","start":"2026-03-01T12:51:30.225534Z","end":"2026-03-01T12:51:30.371517Z","steps":["trace[264049804] 'agreement among raft nodes before linearized reading' (duration: 145.324695ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:51:38.667896Z","caller":"traceutil/trace.go:171","msg":"trace[1652257191] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"246.59237ms","start":"2026-03-01T12:51:38.421278Z","end":"2026-03-01T12:51:38.667871Z","steps":["trace[1652257191] 'read index received' (duration: 246.397864ms)","trace[1652257191] 'applied index is now lower than readState.Index' (duration: 193.636µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:51:38.66807Z","caller":"traceutil/trace.go:171","msg":"trace[1229310525] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"253.537089ms","start":"2026-03-01T12:51:38.414508Z","end":"2026-03-01T12:51:38.668045Z","steps":["trace[1229310525] 'process raft request' (duration: 253.226809ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:51:38.66815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.784856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-01T12:51:38.668195Z","caller":"traceutil/trace.go:171","msg":"trace[1304726929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"246.865549ms","start":"2026-03-01T12:51:38.421316Z","end":"2026-03-01T12:51:38.668181Z","steps":["trace[1304726929] 'agreement among raft nodes before linearized reading' (duration: 246.692534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:51:38.668261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.926001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-01T12:51:38.668388Z","caller":"traceutil/trace.go:171","msg":"trace[1180664751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:952; }","duration":"247.094416ms","start":"2026-03-01T12:51:38.421274Z","end":"2026-03-01T12:51:38.668368Z","steps":["trace[1180664751] 'agreement among raft nodes before linearized reading' (duration: 246.732285ms)"],"step_count":1}