{"level":"warn","ts":"2026-09-11T00:22:19.5793Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-11T00:22:19.579427Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-09-11T00:22:19.579516Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-11T00:22:19.579573Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-09-11T00:22:19.579599Z","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-09-11T00:22:19.585209Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-09-11T00:22:19.585547Z","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.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-09-11T00:22:19.592968Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.566337ms"} {"level":"info","ts":"2026-09-11T00:22:19.601636Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-09-11T00:22:19.60173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-09-11T00:22:19.601758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-09-11T00:22:19.601798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-11T00:22:19.601815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-09-11T00:22:19.601881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-09-11T00:22:19.610746Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-11T00:22:19.612328Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-11T00:22:19.613889Z","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-09-11T00:22:19.616007Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-11T00:22:19.616257Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-11T00:22:19.616316Z","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-09-11T00:22:19.624482Z","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-09-11T00:22:19.624575Z","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-09-11T00:22:19.625146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-09-11T00:22:19.625395Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-09-11T00:22:19.633589Z","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-09-11T00:22:19.6338Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-11T00:22:19.6338Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-09-11T00:22:19.633825Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-11T00:22:19.633842Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-09-11T00:22:20.303048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-09-11T00:22:20.30312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-11T00:22:20.303209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-09-11T00:22:20.303238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-09-11T00:22:20.303254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-09-11T00:22:20.30327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-09-11T00:22:20.303288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-09-11T00:22:20.304231Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:22:20.304873Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T00:22:20.304884Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-09-11T00:22:20.304995Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-11T00:22:20.305255Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-11T00:22:20.305292Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-11T00:22:20.305379Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:22:20.305529Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:22:20.305581Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-11T00:22:20.30844Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-11T00:22:20.308819Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-09-11T00:22:30.367254Z","caller":"traceutil/trace.go:171","msg":"trace[1622587874] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"143.622101ms","start":"2026-09-11T00:22:30.223611Z","end":"2026-09-11T00:22:30.367233Z","steps":["trace[1622587874] 'read index received' (duration: 143.481699ms)","trace[1622587874] 'applied index is now lower than readState.Index' (duration: 139.562µs)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:22:30.367447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.804473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-11T00:22:30.367625Z","caller":"traceutil/trace.go:171","msg":"trace[1208745534] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:342; }","duration":"144.035095ms","start":"2026-09-11T00:22:30.223574Z","end":"2026-09-11T00:22:30.367609Z","steps":["trace[1208745534] 'agreement among raft nodes before linearized reading' (duration: 143.753782ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:22:30.3673Z","caller":"traceutil/trace.go:171","msg":"trace[1050520807] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"184.280923ms","start":"2026-09-11T00:22:30.18299Z","end":"2026-09-11T00:22:30.367271Z","steps":["trace[1050520807] 'process raft request' (duration: 184.12578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:22:30.725668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.436312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T00:22:30.725762Z","caller":"traceutil/trace.go:171","msg":"trace[337121466] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"350.44365ms","start":"2026-09-11T00:22:30.375304Z","end":"2026-09-11T00:22:30.725748Z","steps":["trace[337121466] 'read index received' (duration: 59.986601ms)","trace[337121466] 'applied index is now lower than readState.Index' (duration: 290.455959ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:22:30.725838Z","caller":"traceutil/trace.go:171","msg":"trace[1533824763] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"350.594001ms","start":"2026-09-11T00:22:30.375234Z","end":"2026-09-11T00:22:30.725828Z","steps":["trace[1533824763] 'process raft request' (duration: 211.36044ms)","trace[1533824763] 'compare' (duration: 138.30935ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:22:30.725896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:22:30.375215Z","time spent":"350.652042ms","remote":"127.0.0.1:54166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T00:22:30.726024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.697653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-09-11T00:22:30.726078Z","caller":"traceutil/trace.go:171","msg":"trace[425280531] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:344; }","duration":"350.822363ms","start":"2026-09-11T00:22:30.375244Z","end":"2026-09-11T00:22:30.726066Z","steps":["trace[425280531] 'agreement among raft nodes before linearized reading' (duration: 350.699732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:22:30.726103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:22:30.375228Z","time spent":"350.870274ms","remote":"127.0.0.1:54110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-09-11T00:22:30.726118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.59698ms","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-09-11T00:22:30.726096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.109352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-09-11T00:22:30.726204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.841491ms","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-09-11T00:22:30.72623Z","caller":"traceutil/trace.go:171","msg":"trace[377981434] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:344; }","duration":"252.895252ms","start":"2026-09-11T00:22:30.47333Z","end":"2026-09-11T00:22:30.726225Z","steps":["trace[377981434] 'agreement among raft nodes before linearized reading' (duration: 252.850831ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:22:30.726264Z","caller":"traceutil/trace.go:171","msg":"trace[234145365] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:344; }","duration":"202.757002ms","start":"2026-09-11T00:22:30.523443Z","end":"2026-09-11T00:22:30.7262Z","steps":["trace[234145365] 'agreement among raft nodes before linearized reading' (duration: 202.55975ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:22:30.726287Z","caller":"traceutil/trace.go:171","msg":"trace[227364347] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:344; }","duration":"303.253174ms","start":"2026-09-11T00:22:30.422959Z","end":"2026-09-11T00:22:30.726213Z","steps":["trace[227364347] 'agreement among raft nodes before linearized reading' (duration: 302.961761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:22:30.726158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.799135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-09-11T00:22:30.726414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:22:30.422908Z","time spent":"303.487466ms","remote":"127.0.0.1:54110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-09-11T00:22:30.726431Z","caller":"traceutil/trace.go:171","msg":"trace[1891682851] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:344; }","duration":"153.100908ms","start":"2026-09-11T00:22:30.573317Z","end":"2026-09-11T00:22:30.726418Z","steps":["trace[1891682851] 'agreement among raft nodes before linearized reading' (duration: 152.798965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:22:30.726354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.189922ms","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-09-11T00:22:30.7268Z","caller":"traceutil/trace.go:171","msg":"trace[1360092319] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:344; }","duration":"103.701338ms","start":"2026-09-11T00:22:30.623091Z","end":"2026-09-11T00:22:30.726792Z","steps":["trace[1360092319] 'agreement among raft nodes before linearized reading' (duration: 103.190802ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:22:30.973975Z","caller":"traceutil/trace.go:171","msg":"trace[1663195191] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"240.199037ms","start":"2026-09-11T00:22:30.733755Z","end":"2026-09-11T00:22:30.973954Z","steps":["trace[1663195191] 'process raft request' (duration: 240.030696ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:22:31.08734Z","caller":"traceutil/trace.go:171","msg":"trace[1991628658] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"108.504706ms","start":"2026-09-11T00:22:30.978818Z","end":"2026-09-11T00:22:31.087322Z","steps":["trace[1991628658] 'process raft request' (duration: 108.471616ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:22:31.087359Z","caller":"traceutil/trace.go:171","msg":"trace[1421235152] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:355; }","duration":"313.011648ms","start":"2026-09-11T00:22:30.774322Z","end":"2026-09-11T00:22:31.087334Z","steps":["trace[1421235152] 'read index received' (duration: 199.449734ms)","trace[1421235152] 'applied index is now lower than readState.Index' (duration: 113.559814ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:22:31.087586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.250951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-09-11T00:22:31.08761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.933651ms","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-09-11T00:22:31.08764Z","caller":"traceutil/trace.go:171","msg":"trace[1691627952] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:347; }","duration":"263.999433ms","start":"2026-09-11T00:22:30.82363Z","end":"2026-09-11T00:22:31.087629Z","steps":["trace[1691627952] 'agreement among raft nodes before linearized reading' (duration: 263.870071ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:22:31.087365Z","caller":"traceutil/trace.go:171","msg":"trace[435051512] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"351.418854ms","start":"2026-09-11T00:22:30.735915Z","end":"2026-09-11T00:22:31.087334Z","steps":["trace[435051512] 'process raft request' (duration: 338.032696ms)","trace[435051512] 'compare' (duration: 13.210316ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:22:31.087736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:22:30.735886Z","time spent":"351.798599ms","remote":"127.0.0.1:54082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T00:22:31.087635Z","caller":"traceutil/trace.go:171","msg":"trace[1338353343] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:347; }","duration":"313.317651ms","start":"2026-09-11T00:22:30.774303Z","end":"2026-09-11T00:22:31.087621Z","steps":["trace[1338353343] 'agreement among raft nodes before linearized reading' (duration: 313.14687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:22:31.087783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.247535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-09-11T00:22:31.087811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:22:30.774235Z","time spent":"313.567943ms","remote":"127.0.0.1:54110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-09-11T00:22:31.087837Z","caller":"traceutil/trace.go:171","msg":"trace[713459594] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:347; }","duration":"213.314966ms","start":"2026-09-11T00:22:30.874509Z","end":"2026-09-11T00:22:31.087824Z","steps":["trace[713459594] 'agreement among raft nodes before linearized reading' (duration: 213.225435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:22:31.087971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.059447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-09-11T00:22:31.087996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.366461ms","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-09-11T00:22:31.088007Z","caller":"traceutil/trace.go:171","msg":"trace[1215526164] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:347; }","duration":"164.116448ms","start":"2026-09-11T00:22:30.923882Z","end":"2026-09-11T00:22:31.087998Z","steps":["trace[1215526164] 'agreement among raft nodes before linearized reading' (duration: 164.055068ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:22:31.088017Z","caller":"traceutil/trace.go:171","msg":"trace[521585941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:347; }","duration":"208.426702ms","start":"2026-09-11T00:22:30.879584Z","end":"2026-09-11T00:22:31.088011Z","steps":["trace[521585941] 'agreement among raft nodes before linearized reading' (duration: 208.383421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:22:31.088342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.955878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-09-11T00:22:31.088759Z","caller":"traceutil/trace.go:171","msg":"trace[1273823809] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:347; }","duration":"114.181151ms","start":"2026-09-11T00:22:30.974348Z","end":"2026-09-11T00:22:31.088529Z","steps":["trace[1273823809] 'agreement among raft nodes before linearized reading' (duration: 113.893528ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:23:08.137628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.879916ms","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-09-11T00:23:08.137758Z","caller":"traceutil/trace.go:171","msg":"trace[1734985651] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:646; }","duration":"282.03106ms","start":"2026-09-11T00:23:07.855709Z","end":"2026-09-11T00:23:08.13774Z","steps":["trace[1734985651] 'range keys from in-memory index tree' (duration: 281.858634ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:23:08.644377Z","caller":"traceutil/trace.go:171","msg":"trace[2123538013] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"318.864868ms","start":"2026-09-11T00:23:08.325486Z","end":"2026-09-11T00:23:08.644351Z","steps":["trace[2123538013] 'read index received' (duration: 318.172739ms)","trace[2123538013] 'applied index is now lower than readState.Index' (duration: 691.039µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:23:08.644452Z","caller":"traceutil/trace.go:171","msg":"trace[702936373] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"429.60379ms","start":"2026-09-11T00:23:08.214811Z","end":"2026-09-11T00:23:08.644415Z","steps":["trace[702936373] 'process raft request' (duration: 429.004663ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:23:08.64465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.150516ms","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-09-11T00:23:08.64481Z","caller":"traceutil/trace.go:171","msg":"trace[1248064486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"319.31469ms","start":"2026-09-11T00:23:08.325425Z","end":"2026-09-11T00:23:08.64474Z","steps":["trace[1248064486] 'agreement among raft nodes before linearized reading' (duration: 319.020013ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:23:08.644869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:23:08.214766Z","time spent":"429.972769ms","remote":"127.0.0.1:54016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-11T00:23:08.644894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:23:08.325404Z","time spent":"319.451593ms","remote":"127.0.0.1:54180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-11T00:23:09.051414Z","caller":"traceutil/trace.go:171","msg":"trace[2139673854] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"402.007806ms","start":"2026-09-11T00:23:08.649373Z","end":"2026-09-11T00:23:09.051381Z","steps":["trace[2139673854] 'process raft request' (duration: 314.960593ms)","trace[2139673854] 'compare' (duration: 86.744594ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:23:09.051439Z","caller":"traceutil/trace.go:171","msg":"trace[1795740319] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"401.656917ms","start":"2026-09-11T00:23:08.649752Z","end":"2026-09-11T00:23:09.051409Z","steps":["trace[1795740319] 'process raft request' (duration: 401.533294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:23:09.051571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:23:08.649351Z","time spent":"402.137399ms","remote":"127.0.0.1:54180","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-09-11T00:23:09.051593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:23:08.649742Z","time spent":"401.759289ms","remote":"127.0.0.1:54180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-11T00:23:09.064363Z","caller":"traceutil/trace.go:171","msg":"trace[639823163] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"345.109997ms","start":"2026-09-11T00:23:08.71899Z","end":"2026-09-11T00:23:09.0641Z","steps":["trace[639823163] 'process raft request' (duration: 344.186282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:23:09.064504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-11T00:23:08.718963Z","time spent":"345.471737ms","remote":"127.0.0.1:54422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-11T00:23:24.680232Z","caller":"traceutil/trace.go:171","msg":"trace[854562762] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"104.127811ms","start":"2026-09-11T00:23:24.576079Z","end":"2026-09-11T00:23:24.680207Z","steps":["trace[854562762] 'read index received' (duration: 103.872063ms)","trace[854562762] 'applied index is now lower than readState.Index' (duration: 254.488µs)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:23:24.680572Z","caller":"traceutil/trace.go:171","msg":"trace[273491200] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"187.568682ms","start":"2026-09-11T00:23:24.492978Z","end":"2026-09-11T00:23:24.680547Z","steps":["trace[273491200] 'process raft request' (duration: 187.056596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:23:24.680599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.501093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:23:24.680647Z","caller":"traceutil/trace.go:171","msg":"trace[1429547828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"104.583725ms","start":"2026-09-11T00:23:24.576052Z","end":"2026-09-11T00:23:24.680636Z","steps":["trace[1429547828] 'agreement among raft nodes before linearized reading' (duration: 104.486023ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:23:24.902854Z","caller":"traceutil/trace.go:171","msg":"trace[849223173] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"214.254217ms","start":"2026-09-11T00:23:24.688575Z","end":"2026-09-11T00:23:24.902829Z","steps":["trace[849223173] 'process raft request' (duration: 214.081811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:24:48.266601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.956834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-09-11T00:24:48.266685Z","caller":"traceutil/trace.go:171","msg":"trace[435076993] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1907; }","duration":"241.088249ms","start":"2026-09-11T00:24:48.025584Z","end":"2026-09-11T00:24:48.266672Z","steps":["trace[435076993] 'range keys from in-memory index tree' (duration: 240.775197ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:24:48.266582Z","caller":"traceutil/trace.go:171","msg":"trace[1740557792] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"171.785383ms","start":"2026-09-11T00:24:48.094774Z","end":"2026-09-11T00:24:48.266559Z","steps":["trace[1740557792] 'process raft request' (duration: 166.607348ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:24:48.453146Z","caller":"traceutil/trace.go:171","msg":"trace[776882533] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1983; }","duration":"144.08114ms","start":"2026-09-11T00:24:48.309039Z","end":"2026-09-11T00:24:48.45312Z","steps":["trace[776882533] 'read index received' (duration: 124.088587ms)","trace[776882533] 'applied index is now lower than readState.Index' (duration: 19.991383ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:24:48.453536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.419823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-11T00:24:48.453581Z","caller":"traceutil/trace.go:171","msg":"trace[12769313] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1909; }","duration":"144.55835ms","start":"2026-09-11T00:24:48.309008Z","end":"2026-09-11T00:24:48.453566Z","steps":["trace[12769313] 'agreement among raft nodes before linearized reading' (duration: 144.240566ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:24:48.453771Z","caller":"traceutil/trace.go:171","msg":"trace[62563836] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"174.882673ms","start":"2026-09-11T00:24:48.27867Z","end":"2026-09-11T00:24:48.453553Z","steps":["trace[62563836] 'process raft request' (duration: 154.555726ms)","trace[62563836] 'compare' (duration: 19.760253ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:24:48.700276Z","caller":"traceutil/trace.go:171","msg":"trace[342637903] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"237.263409ms","start":"2026-09-11T00:24:48.462984Z","end":"2026-09-11T00:24:48.700248Z","steps":["trace[342637903] 'process raft request' (duration: 236.878313ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:24:48.700597Z","caller":"traceutil/trace.go:171","msg":"trace[2012025538] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"237.318152ms","start":"2026-09-11T00:24:48.463257Z","end":"2026-09-11T00:24:48.700575Z","steps":["trace[2012025538] 'process raft request' (duration: 236.858723ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:24:48.700613Z","caller":"traceutil/trace.go:171","msg":"trace[1979217934] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"237.334152ms","start":"2026-09-11T00:24:48.463252Z","end":"2026-09-11T00:24:48.700587Z","steps":["trace[1979217934] 'process raft request' (duration: 237.140764ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:24:48.706458Z","caller":"traceutil/trace.go:171","msg":"trace[140316099] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"192.979336ms","start":"2026-09-11T00:24:48.513455Z","end":"2026-09-11T00:24:48.706434Z","steps":["trace[140316099] 'process raft request' (duration: 192.797928ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:24:48.706641Z","caller":"traceutil/trace.go:171","msg":"trace[513961357] linearizableReadLoop","detail":"{readStateIndex:1989; appliedIndex:1985; }","duration":"130.534626ms","start":"2026-09-11T00:24:48.576081Z","end":"2026-09-11T00:24:48.706616Z","steps":["trace[513961357] 'read index received' (duration: 123.770943ms)","trace[513961357] 'applied index is now lower than readState.Index' (duration: 6.761973ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:24:48.706802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.704042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:24:48.70685Z","caller":"traceutil/trace.go:171","msg":"trace[389317749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1915; }","duration":"130.801877ms","start":"2026-09-11T00:24:48.576033Z","end":"2026-09-11T00:24:48.706835Z","steps":["trace[389317749] 'agreement among raft nodes before linearized reading' (duration: 130.706223ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:25:01.004886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.355794ms","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-09-11T00:25:01.004985Z","caller":"traceutil/trace.go:171","msg":"trace[1059194600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2014; }","duration":"147.509661ms","start":"2026-09-11T00:25:00.857453Z","end":"2026-09-11T00:25:01.004962Z","steps":["trace[1059194600] 'range keys from in-memory index tree' (duration: 147.124545ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:25:22.561176Z","caller":"traceutil/trace.go:171","msg":"trace[1272022802] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"110.522241ms","start":"2026-09-11T00:25:22.450631Z","end":"2026-09-11T00:25:22.561153Z","steps":["trace[1272022802] 'process raft request' (duration: 110.369054ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:25:23.524464Z","caller":"traceutil/trace.go:171","msg":"trace[662513965] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"146.704591ms","start":"2026-09-11T00:25:23.377737Z","end":"2026-09-11T00:25:23.524442Z","steps":["trace[662513965] 'process raft request' (duration: 146.588276ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:25:23.910667Z","caller":"traceutil/trace.go:171","msg":"trace[361241356] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"276.565262ms","start":"2026-09-11T00:25:23.634076Z","end":"2026-09-11T00:25:23.910641Z","steps":["trace[361241356] 'process raft request' (duration: 276.319822ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:25:23.910944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.233557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-11T00:25:23.911014Z","caller":"traceutil/trace.go:171","msg":"trace[1087853771] linearizableReadLoop","detail":"{readStateIndex:2238; appliedIndex:2236; }","duration":"153.861982ms","start":"2026-09-11T00:25:23.756691Z","end":"2026-09-11T00:25:23.910553Z","steps":["trace[1087853771] 'read index received' (duration: 17.644745ms)","trace[1087853771] 'applied index is now lower than readState.Index' (duration: 136.216286ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:25:23.911038Z","caller":"traceutil/trace.go:171","msg":"trace[2083421824] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2155; }","duration":"154.374114ms","start":"2026-09-11T00:25:23.756642Z","end":"2026-09-11T00:25:23.911017Z","steps":["trace[2083421824] 'agreement among raft nodes before linearized reading' (duration: 154.135403ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:25:24.085608Z","caller":"traceutil/trace.go:171","msg":"trace[1431857055] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2238; }","duration":"174.521778ms","start":"2026-09-11T00:25:23.91106Z","end":"2026-09-11T00:25:24.085581Z","steps":["trace[1431857055] 'read index received' (duration: 166.015058ms)","trace[1431857055] 'applied index is now lower than readState.Index' (duration: 8.50591ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:25:24.085818Z","caller":"traceutil/trace.go:171","msg":"trace[20662850] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"226.425635ms","start":"2026-09-11T00:25:23.85934Z","end":"2026-09-11T00:25:24.085766Z","steps":["trace[20662850] 'process raft request' (duration: 217.757169ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:25:24.085844Z","caller":"traceutil/trace.go:171","msg":"trace[1784247804] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"221.537274ms","start":"2026-09-11T00:25:23.864266Z","end":"2026-09-11T00:25:24.085803Z","steps":["trace[1784247804] 'process raft request' (duration: 221.263911ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:25:24.085948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.330091ms","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-09-11T00:25:24.086508Z","caller":"traceutil/trace.go:171","msg":"trace[1130957544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2157; }","duration":"296.914646ms","start":"2026-09-11T00:25:23.789576Z","end":"2026-09-11T00:25:24.086491Z","steps":["trace[1130957544] 'agreement among raft nodes before linearized reading' (duration: 296.283569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:25:24.085946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.704511ms","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-09-11T00:25:24.088471Z","caller":"traceutil/trace.go:171","msg":"trace[579469642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2157; }","duration":"294.269333ms","start":"2026-09-11T00:25:23.794182Z","end":"2026-09-11T00:25:24.088451Z","steps":["trace[579469642] 'agreement among raft nodes before linearized reading' (duration: 291.615888ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:26:32.115985Z","caller":"traceutil/trace.go:171","msg":"trace[1349993192] transaction","detail":"{read_only:false; response_revision:2624; number_of_response:1; }","duration":"249.398404ms","start":"2026-09-11T00:26:31.866568Z","end":"2026-09-11T00:26:32.115966Z","steps":["trace[1349993192] 'process raft request' (duration: 249.3099ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:27:03.037748Z","caller":"traceutil/trace.go:171","msg":"trace[1286710021] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"115.787571ms","start":"2026-09-11T00:27:02.92155Z","end":"2026-09-11T00:27:03.037338Z","steps":["trace[1286710021] 'process raft request' (duration: 115.607061ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:27:08.412397Z","caller":"traceutil/trace.go:171","msg":"trace[1147827813] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"114.758892ms","start":"2026-09-11T00:27:08.297549Z","end":"2026-09-11T00:27:08.412308Z","steps":["trace[1147827813] 'process raft request' (duration: 114.379274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:27:08.642693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.885185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T00:27:08.643251Z","caller":"traceutil/trace.go:171","msg":"trace[2100422505] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"226.740953ms","start":"2026-09-11T00:27:08.416477Z","end":"2026-09-11T00:27:08.643218Z","steps":["trace[2100422505] 'process raft request' (duration: 91.245171ms)","trace[2100422505] 'compare' (duration: 134.479325ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:27:08.643773Z","caller":"traceutil/trace.go:171","msg":"trace[1848991822] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"165.558821ms","start":"2026-09-11T00:27:08.478191Z","end":"2026-09-11T00:27:08.64375Z","steps":["trace[1848991822] 'process raft request' (duration: 165.064202ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:31:28.230186Z","caller":"traceutil/trace.go:171","msg":"trace[526969731] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"107.276009ms","start":"2026-09-11T00:31:28.122881Z","end":"2026-09-11T00:31:28.230157Z","steps":["trace[526969731] 'process raft request' (duration: 51.947102ms)","trace[526969731] 'compare' (duration: 55.186522ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:31:28.438338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.553659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:31:28.438419Z","caller":"traceutil/trace.go:171","msg":"trace[185918116] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:4888; }","duration":"102.685133ms","start":"2026-09-11T00:31:28.335718Z","end":"2026-09-11T00:31:28.438403Z","steps":["trace[185918116] 'count revisions from in-memory index tree' (duration: 102.469336ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:31:28.593626Z","caller":"traceutil/trace.go:171","msg":"trace[2132024621] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"111.812333ms","start":"2026-09-11T00:31:28.481784Z","end":"2026-09-11T00:31:28.593597Z","steps":["trace[2132024621] 'process raft request' (duration: 111.554065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:31:34.237216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.059484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-11T00:31:34.237337Z","caller":"traceutil/trace.go:171","msg":"trace[1799432080] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"221.106017ms","start":"2026-09-11T00:31:34.016198Z","end":"2026-09-11T00:31:34.237304Z","steps":["trace[1799432080] 'process raft request' (duration: 76.865967ms)","trace[1799432080] 'compare' (duration: 143.875718ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:31:34.288674Z","caller":"traceutil/trace.go:171","msg":"trace[1600328093] linearizableReadLoop","detail":"{readStateIndex:5126; appliedIndex:5123; }","duration":"262.658487ms","start":"2026-09-11T00:31:34.025999Z","end":"2026-09-11T00:31:34.288657Z","steps":["trace[1600328093] 'read index received' (duration: 67.125404ms)","trace[1600328093] 'applied index is now lower than readState.Index' (duration: 195.531993ms)"],"step_count":2} {"level":"info","ts":"2026-09-11T00:31:34.28877Z","caller":"traceutil/trace.go:171","msg":"trace[1570425978] transaction","detail":"{read_only:false; response_revision:4935; number_of_response:1; }","duration":"263.537966ms","start":"2026-09-11T00:31:34.025203Z","end":"2026-09-11T00:31:34.288741Z","steps":["trace[1570425978] 'process raft request' (duration: 263.398062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:31:34.288864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.860723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-11T00:31:34.288906Z","caller":"traceutil/trace.go:171","msg":"trace[279788822] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4935; }","duration":"262.936617ms","start":"2026-09-11T00:31:34.025957Z","end":"2026-09-11T00:31:34.288894Z","steps":["trace[279788822] 'agreement among raft nodes before linearized reading' (duration: 262.798911ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:31:34.288812Z","caller":"traceutil/trace.go:171","msg":"trace[1446291686] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"271.80619ms","start":"2026-09-11T00:31:34.016975Z","end":"2026-09-11T00:31:34.288781Z","steps":["trace[1446291686] 'process raft request' (duration: 271.51232ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:31:34.364002Z","caller":"traceutil/trace.go:171","msg":"trace[2135958124] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"223.786199ms","start":"2026-09-11T00:31:34.14019Z","end":"2026-09-11T00:31:34.363976Z","steps":["trace[2135958124] 'process raft request' (duration: 207.364368ms)","trace[2135958124] 'compare' (duration: 16.167473ms)"],"step_count":2} {"level":"warn","ts":"2026-09-11T00:31:34.364098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.029381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-11T00:31:34.364199Z","caller":"traceutil/trace.go:171","msg":"trace[2060617999] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:4936; }","duration":"139.176226ms","start":"2026-09-11T00:31:34.225004Z","end":"2026-09-11T00:31:34.36418Z","steps":["trace[2060617999] 'agreement among raft nodes before linearized reading' (duration: 139.015991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:31:34.364579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.083686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-11T00:31:34.364603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.785015ms","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-09-11T00:31:34.364638Z","caller":"traceutil/trace.go:171","msg":"trace[974662722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4936; }","duration":"111.1892ms","start":"2026-09-11T00:31:34.253433Z","end":"2026-09-11T00:31:34.364622Z","steps":["trace[974662722] 'agreement among raft nodes before linearized reading' (duration: 111.016613ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:31:34.36468Z","caller":"traceutil/trace.go:171","msg":"trace[634717049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4936; }","duration":"210.873787ms","start":"2026-09-11T00:31:34.15376Z","end":"2026-09-11T00:31:34.364634Z","steps":["trace[634717049] 'agreement among raft nodes before linearized reading' (duration: 210.749562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-11T00:31:41.279217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.713685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-11T00:31:41.280059Z","caller":"traceutil/trace.go:171","msg":"trace[791248643] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5099; }","duration":"131.539183ms","start":"2026-09-11T00:31:41.14844Z","end":"2026-09-11T00:31:41.279979Z","steps":["trace[791248643] 'range keys from in-memory index tree' (duration: 129.11705ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:32:20.339876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2887} {"level":"info","ts":"2026-09-11T00:32:20.430548Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2887,"took":"89.355779ms","hash":25011975,"current-db-size-bytes":53686272,"current-db-size":"54 MB","current-db-size-in-use-bytes":28446720,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-11T00:32:20.430615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":25011975,"revision":2887,"compact-revision":-1} {"level":"info","ts":"2026-09-11T00:35:35.428334Z","caller":"traceutil/trace.go:171","msg":"trace[1465533063] transaction","detail":"{read_only:false; response_revision:6802; number_of_response:1; }","duration":"100.058328ms","start":"2026-09-11T00:35:35.328245Z","end":"2026-09-11T00:35:35.428304Z","steps":["trace[1465533063] 'process raft request' (duration: 90.574335ms)"],"step_count":1} {"level":"info","ts":"2026-09-11T00:37:20.349209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5462} {"level":"info","ts":"2026-09-11T00:37:20.490433Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5462,"took":"139.646109ms","hash":3410594211,"current-db-size-bytes":53686272,"current-db-size":"54 MB","current-db-size-in-use-bytes":18964480,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-11T00:37:20.490529Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3410594211,"revision":5462,"compact-revision":2887}