{"level":"warn","ts":"2026-09-15T22:17:48.765319Z","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-15T22:17:48.765407Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.223: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.223:2380","--initial-cluster=instance=https://162.253.55.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.223: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-15T22:17:48.765473Z","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-15T22:17:48.765483Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.223:2380"]} {"level":"info","ts":"2026-09-15T22:17:48.765543Z","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-15T22:17:48.766172Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"]} {"level":"info","ts":"2026-09-15T22:17:48.766251Z","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.223:2380"],"listen-peer-urls":["https://162.253.55.223:2380"],"advertise-client-urls":["https://162.253.55.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.223: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-15T22:17:48.772188Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.725454ms"} {"level":"info","ts":"2026-09-15T22:17:48.780892Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0f50f8a6c5f7d06","cluster-id":"62f129f77cf8e487"} {"level":"info","ts":"2026-09-15T22:17:48.780988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=()"} {"level":"info","ts":"2026-09-15T22:17:48.781013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became follower at term 0"} {"level":"info","ts":"2026-09-15T22:17:48.781029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0f50f8a6c5f7d06 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-15T22:17:48.781043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became follower at term 1"} {"level":"info","ts":"2026-09-15T22:17:48.781082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=(11598193502560681222)"} {"level":"warn","ts":"2026-09-15T22:17:48.789804Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-15T22:17:48.793321Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-15T22:17:48.7946Z","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-15T22:17:48.79684Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0f50f8a6c5f7d06","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-15T22:17:48.797293Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0f50f8a6c5f7d06","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-15T22:17:48.79734Z","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-15T22:17:48.797478Z","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-15T22:17:48.797487Z","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-15T22:17:48.798722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 switched to configuration voters=(11598193502560681222)"} {"level":"info","ts":"2026-09-15T22:17:48.800136Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62f129f77cf8e487","local-member-id":"a0f50f8a6c5f7d06","added-peer-id":"a0f50f8a6c5f7d06","added-peer-peer-urls":["https://162.253.55.223:2380"]} {"level":"info","ts":"2026-09-15T22:17:48.80177Z","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-15T22:17:48.801871Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.223:2380"} {"level":"info","ts":"2026-09-15T22:17:48.801894Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.223:2380"} {"level":"info","ts":"2026-09-15T22:17:48.802061Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0f50f8a6c5f7d06","initial-advertise-peer-urls":["https://162.253.55.223:2380"],"listen-peer-urls":["https://162.253.55.223:2380"],"advertise-client-urls":["https://162.253.55.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-15T22:17:48.80211Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-15T22:17:48.882309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 is starting a new election at term 1"} {"level":"info","ts":"2026-09-15T22:17:48.882443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-15T22:17:48.882524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 received MsgPreVoteResp from a0f50f8a6c5f7d06 at term 1"} {"level":"info","ts":"2026-09-15T22:17:48.882572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became candidate at term 2"} {"level":"info","ts":"2026-09-15T22:17:48.8826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 received MsgVoteResp from a0f50f8a6c5f7d06 at term 2"} {"level":"info","ts":"2026-09-15T22:17:48.88263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0f50f8a6c5f7d06 became leader at term 2"} {"level":"info","ts":"2026-09-15T22:17:48.882659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0f50f8a6c5f7d06 elected leader a0f50f8a6c5f7d06 at term 2"} {"level":"info","ts":"2026-09-15T22:17:48.883665Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0f50f8a6c5f7d06","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.223:2379]}","request-path":"/0/members/a0f50f8a6c5f7d06/attributes","cluster-id":"62f129f77cf8e487","publish-timeout":"7s"} {"level":"info","ts":"2026-09-15T22:17:48.883672Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:17:48.883685Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:17:48.883713Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:17:48.883871Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-15T22:17:48.883897Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-15T22:17:48.885715Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-15T22:17:48.88589Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62f129f77cf8e487","local-member-id":"a0f50f8a6c5f7d06","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:17:48.886011Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:17:48.886047Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:17:48.898794Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.223:2379"} {"level":"info","ts":"2026-09-15T22:17:53.09107Z","caller":"traceutil/trace.go:171","msg":"trace[413462543] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"212.268123ms","start":"2026-09-15T22:17:52.878781Z","end":"2026-09-15T22:17:53.091049Z","steps":["trace[413462543] 'process raft request' (duration: 212.139389ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:53.297567Z","caller":"traceutil/trace.go:171","msg":"trace[1220777428] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"199.011544ms","start":"2026-09-15T22:17:53.098507Z","end":"2026-09-15T22:17:53.297518Z","steps":["trace[1220777428] 'process raft request' (duration: 168.365361ms)","trace[1220777428] 'compare' (duration: 30.508488ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:18:31.76197Z","caller":"traceutil/trace.go:171","msg":"trace[1727294100] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"115.924131ms","start":"2026-09-15T22:18:31.646022Z","end":"2026-09-15T22:18:31.761946Z","steps":["trace[1727294100] 'process raft request' (duration: 115.566382ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:18:34.315727Z","caller":"traceutil/trace.go:171","msg":"trace[878295182] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"104.42942ms","start":"2026-09-15T22:18:34.211245Z","end":"2026-09-15T22:18:34.315675Z","steps":["trace[878295182] 'process raft request' (duration: 22.161989ms)","trace[878295182] 'compare' (duration: 82.008946ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:18:35.511888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.559188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:18:35.512051Z","caller":"traceutil/trace.go:171","msg":"trace[1365870447] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"205.289738ms","start":"2026-09-15T22:18:35.306741Z","end":"2026-09-15T22:18:35.512031Z","steps":["trace[1365870447] 'read index received' (duration: 13.733198ms)","trace[1365870447] 'applied index is now lower than readState.Index' (duration: 191.55489ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:18:35.512106Z","caller":"traceutil/trace.go:171","msg":"trace[655797235] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"268.339387ms","start":"2026-09-15T22:18:35.243741Z","end":"2026-09-15T22:18:35.51208Z","steps":["trace[655797235] 'process raft request' (duration: 76.910529ms)","trace[655797235] 'compare' (duration: 190.221761ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:18:35.512174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.434891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-15T22:18:35.512205Z","caller":"traceutil/trace.go:171","msg":"trace[1718962376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"205.533374ms","start":"2026-09-15T22:18:35.30666Z","end":"2026-09-15T22:18:35.512194Z","steps":["trace[1718962376] 'agreement among raft nodes before linearized reading' (duration: 205.445932ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:18:35.544797Z","caller":"traceutil/trace.go:171","msg":"trace[121999965] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"144.773674ms","start":"2026-09-15T22:18:35.399999Z","end":"2026-09-15T22:18:35.544773Z","steps":["trace[121999965] 'process raft request' (duration: 144.632841ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:18:36.300529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9009064744355754537,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-15T22:18:36.316512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.771224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:18:36.316667Z","caller":"traceutil/trace.go:171","msg":"trace[2128319234] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:623; }","duration":"517.176206ms","start":"2026-09-15T22:18:35.799473Z","end":"2026-09-15T22:18:36.316649Z","steps":["trace[2128319234] 'read index received' (duration: 503.303188ms)","trace[2128319234] 'applied index is now lower than readState.Index' (duration: 13.872158ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:18:36.316997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.516993ms","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-15T22:18:36.316999Z","caller":"traceutil/trace.go:171","msg":"trace[49625095] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"745.319794ms","start":"2026-09-15T22:18:35.571641Z","end":"2026-09-15T22:18:36.31696Z","steps":["trace[49625095] 'process raft request' (duration: 139.028987ms)","trace[49625095] 'compare' (duration: 605.635822ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:18:36.317039Z","caller":"traceutil/trace.go:171","msg":"trace[866627280] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"517.592744ms","start":"2026-09-15T22:18:35.799432Z","end":"2026-09-15T22:18:36.317025Z","steps":["trace[866627280] 'agreement among raft nodes before linearized reading' (duration: 517.351179ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:18:36.317069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:18:35.79941Z","time spent":"517.651216ms","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-15T22:18:36.31712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:18:35.571619Z","time spent":"745.433366ms","remote":"127.0.0.1:38288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:18:36.317251Z","caller":"traceutil/trace.go:171","msg":"trace[700080712] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"728.550488ms","start":"2026-09-15T22:18:35.588651Z","end":"2026-09-15T22:18:36.317202Z","steps":["trace[700080712] 'process raft request' (duration: 727.947965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:18:36.317447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:18:35.588627Z","time spent":"728.720972ms","remote":"127.0.0.1:38432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:18:37.809172Z","caller":"traceutil/trace.go:171","msg":"trace[158708858] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"172.681572ms","start":"2026-09-15T22:18:37.636456Z","end":"2026-09-15T22:18:37.809138Z","steps":["trace[158708858] 'process raft request' (duration: 150.080104ms)","trace[158708858] 'compare' (duration: 22.442514ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:18:37.817783Z","caller":"traceutil/trace.go:171","msg":"trace[755686758] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"181.240321ms","start":"2026-09-15T22:18:37.636515Z","end":"2026-09-15T22:18:37.817755Z","steps":["trace[755686758] 'process raft request' (duration: 180.494014ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:18:37.818036Z","caller":"traceutil/trace.go:171","msg":"trace[974388141] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"179.746927ms","start":"2026-09-15T22:18:37.638266Z","end":"2026-09-15T22:18:37.818012Z","steps":["trace[974388141] 'process raft request' (duration: 178.879288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:18:52.207959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.663599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:18:52.208046Z","caller":"traceutil/trace.go:171","msg":"trace[654663260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"205.800852ms","start":"2026-09-15T22:18:52.002228Z","end":"2026-09-15T22:18:52.208029Z","steps":["trace[654663260] 'range keys from in-memory index tree' (duration: 205.529476ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:18:52.296487Z","caller":"traceutil/trace.go:171","msg":"trace[1430351818] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"135.536578ms","start":"2026-09-15T22:18:52.160926Z","end":"2026-09-15T22:18:52.296462Z","steps":["trace[1430351818] 'read index received' (duration: 135.350123ms)","trace[1430351818] 'applied index is now lower than readState.Index' (duration: 185.485µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:18:52.296548Z","caller":"traceutil/trace.go:171","msg":"trace[952533601] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"200.604929ms","start":"2026-09-15T22:18:52.095915Z","end":"2026-09-15T22:18:52.29652Z","steps":["trace[952533601] 'process raft request' (duration: 200.411585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:18:52.296683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.281857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-15T22:18:52.296738Z","caller":"traceutil/trace.go:171","msg":"trace[323973764] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:762; }","duration":"115.382719ms","start":"2026-09-15T22:18:52.181326Z","end":"2026-09-15T22:18:52.296708Z","steps":["trace[323973764] 'agreement among raft nodes before linearized reading' (duration: 115.274067ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:18:52.296843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.881085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-15T22:18:52.296981Z","caller":"traceutil/trace.go:171","msg":"trace[1153802178] range","detail":"{range_begin:/registry/masterleases/162.253.55.223; range_end:; response_count:1; response_revision:762; }","duration":"136.090489ms","start":"2026-09-15T22:18:52.160875Z","end":"2026-09-15T22:18:52.296965Z","steps":["trace[1153802178] 'agreement among raft nodes before linearized reading' (duration: 135.715741ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:19:53.144634Z","caller":"traceutil/trace.go:171","msg":"trace[388660159] linearizableReadLoop","detail":"{readStateIndex:1512; appliedIndex:1511; }","duration":"142.363944ms","start":"2026-09-15T22:19:53.002252Z","end":"2026-09-15T22:19:53.144616Z","steps":["trace[388660159] 'read index received' (duration: 142.241841ms)","trace[388660159] 'applied index is now lower than readState.Index' (duration: 121.363µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:19:53.144706Z","caller":"traceutil/trace.go:171","msg":"trace[322893351] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"251.353541ms","start":"2026-09-15T22:19:52.89334Z","end":"2026-09-15T22:19:53.144693Z","steps":["trace[322893351] 'process raft request' (duration: 251.159647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:19:53.144914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.653299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:19:53.144959Z","caller":"traceutil/trace.go:171","msg":"trace[1371152744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1460; }","duration":"142.746159ms","start":"2026-09-15T22:19:53.002204Z","end":"2026-09-15T22:19:53.14495Z","steps":["trace[1371152744] 'agreement among raft nodes before linearized reading' (duration: 142.560797ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:19:53.169624Z","caller":"traceutil/trace.go:171","msg":"trace[641762107] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"109.116523ms","start":"2026-09-15T22:19:53.060463Z","end":"2026-09-15T22:19:53.16958Z","steps":["trace[641762107] 'process raft request' (duration: 108.91537ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:18.439609Z","caller":"traceutil/trace.go:171","msg":"trace[764714968] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"435.726505ms","start":"2026-09-15T22:20:18.003853Z","end":"2026-09-15T22:20:18.439579Z","steps":["trace[764714968] 'read index received' (duration: 435.424451ms)","trace[764714968] 'applied index is now lower than readState.Index' (duration: 300.734µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:20:18.439674Z","caller":"traceutil/trace.go:171","msg":"trace[448840005] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"450.371608ms","start":"2026-09-15T22:20:17.989259Z","end":"2026-09-15T22:20:18.43963Z","steps":["trace[448840005] 'process raft request' (duration: 450.144785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:20:18.439885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:20:17.989223Z","time spent":"450.54205ms","remote":"127.0.0.1:38288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:20:18.439885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.467105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4a375fd5-6d90-4a69-bbbb-76d54c13e646\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-09-15T22:20:18.439955Z","caller":"traceutil/trace.go:171","msg":"trace[508527013] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4a375fd5-6d90-4a69-bbbb-76d54c13e646; range_end:; response_count:1; response_revision:1865; }","duration":"332.595087ms","start":"2026-09-15T22:20:18.10734Z","end":"2026-09-15T22:20:18.439935Z","steps":["trace[508527013] 'agreement among raft nodes before linearized reading' (duration: 332.441075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:20:18.439966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.011939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-15T22:20:18.44003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:20:18.107317Z","time spent":"332.685968ms","remote":"127.0.0.1:38288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4a375fd5-6d90-4a69-bbbb-76d54c13e646\" "} {"level":"info","ts":"2026-09-15T22:20:18.440365Z","caller":"traceutil/trace.go:171","msg":"trace[1491909774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1865; }","duration":"436.516196ms","start":"2026-09-15T22:20:18.00378Z","end":"2026-09-15T22:20:18.440297Z","steps":["trace[1491909774] 'agreement among raft nodes before linearized reading' (duration: 435.965629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:20:18.44021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.998167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-09-15T22:20:18.440469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:20:18.003752Z","time spent":"436.699409ms","remote":"127.0.0.1:38108","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-09-15T22:20:18.440491Z","caller":"traceutil/trace.go:171","msg":"trace[651192363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1865; }","duration":"244.325432ms","start":"2026-09-15T22:20:18.196149Z","end":"2026-09-15T22:20:18.440474Z","steps":["trace[651192363] 'agreement among raft nodes before linearized reading' (duration: 243.923776ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:20:18.440199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.821029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-15T22:20:18.440859Z","caller":"traceutil/trace.go:171","msg":"trace[1156842035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1865; }","duration":"216.55681ms","start":"2026-09-15T22:20:18.224281Z","end":"2026-09-15T22:20:18.440838Z","steps":["trace[1156842035] 'agreement among raft nodes before linearized reading' (duration: 215.739159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:20:18.440491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.682171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-15T22:20:18.44106Z","caller":"traceutil/trace.go:171","msg":"trace[1190289371] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1865; }","duration":"177.392261ms","start":"2026-09-15T22:20:18.263627Z","end":"2026-09-15T22:20:18.441019Z","steps":["trace[1190289371] 'agreement among raft nodes before linearized reading' (duration: 176.516898ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:18.680598Z","caller":"traceutil/trace.go:171","msg":"trace[206048590] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"233.649135ms","start":"2026-09-15T22:20:18.44683Z","end":"2026-09-15T22:20:18.680479Z","steps":["trace[206048590] 'process raft request' (duration: 192.883264ms)","trace[206048590] 'compare' (duration: 40.580188ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:20:18.680686Z","caller":"traceutil/trace.go:171","msg":"trace[39865809] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"233.366321ms","start":"2026-09-15T22:20:18.447286Z","end":"2026-09-15T22:20:18.680653Z","steps":["trace[39865809] 'process raft request' (duration: 233.149638ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:31.899228Z","caller":"traceutil/trace.go:171","msg":"trace[599041021] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"149.212886ms","start":"2026-09-15T22:20:31.749984Z","end":"2026-09-15T22:20:31.899197Z","steps":["trace[599041021] 'process raft request' (duration: 148.706629ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:31.968402Z","caller":"traceutil/trace.go:171","msg":"trace[1368927974] linearizableReadLoop","detail":"{readStateIndex:2064; appliedIndex:2062; }","duration":"134.296976ms","start":"2026-09-15T22:20:31.83407Z","end":"2026-09-15T22:20:31.968367Z","steps":["trace[1368927974] 'read index received' (duration: 64.934759ms)","trace[1368927974] 'applied index is now lower than readState.Index' (duration: 69.360847ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:20:31.968512Z","caller":"traceutil/trace.go:171","msg":"trace[596227702] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"191.256316ms","start":"2026-09-15T22:20:31.777205Z","end":"2026-09-15T22:20:31.968461Z","steps":["trace[596227702] 'process raft request' (duration: 190.963362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:20:31.968679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.580769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-15T22:20:31.968816Z","caller":"traceutil/trace.go:171","msg":"trace[1142406638] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1981; }","duration":"134.782002ms","start":"2026-09-15T22:20:31.834011Z","end":"2026-09-15T22:20:31.968793Z","steps":["trace[1142406638] 'agreement among raft nodes before linearized reading' (duration: 134.482107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:20:51.951527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.550355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:20:51.951642Z","caller":"traceutil/trace.go:171","msg":"trace[1442060475] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2104; }","duration":"182.718458ms","start":"2026-09-15T22:20:51.768898Z","end":"2026-09-15T22:20:51.951617Z","steps":["trace[1442060475] 'range keys from in-memory index tree' (duration: 182.457975ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:52.969022Z","caller":"traceutil/trace.go:171","msg":"trace[1965923525] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"114.650687ms","start":"2026-09-15T22:20:52.85432Z","end":"2026-09-15T22:20:52.968971Z","steps":["trace[1965923525] 'process raft request' (duration: 114.300293ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:53.283563Z","caller":"traceutil/trace.go:171","msg":"trace[725971514] linearizableReadLoop","detail":"{readStateIndex:2199; appliedIndex:2198; }","duration":"132.94972ms","start":"2026-09-15T22:20:53.150587Z","end":"2026-09-15T22:20:53.283537Z","steps":["trace[725971514] 'read index received' (duration: 132.710317ms)","trace[725971514] 'applied index is now lower than readState.Index' (duration: 238.253µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:20:53.283947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.337285ms","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-09-15T22:20:53.283994Z","caller":"traceutil/trace.go:171","msg":"trace[987791783] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"171.93526ms","start":"2026-09-15T22:20:53.11202Z","end":"2026-09-15T22:20:53.283955Z","steps":["trace[987791783] 'process raft request' (duration: 171.339293ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:53.284034Z","caller":"traceutil/trace.go:171","msg":"trace[1617204558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2111; }","duration":"133.499057ms","start":"2026-09-15T22:20:53.150518Z","end":"2026-09-15T22:20:53.284017Z","steps":["trace[1617204558] 'agreement among raft nodes before linearized reading' (duration: 133.258884ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:53.413282Z","caller":"traceutil/trace.go:171","msg":"trace[757067462] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"122.824381ms","start":"2026-09-15T22:20:53.290424Z","end":"2026-09-15T22:20:53.413249Z","steps":["trace[757067462] 'process raft request' (duration: 115.839471ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:22:00.105697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.918131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:22:00.105823Z","caller":"traceutil/trace.go:171","msg":"trace[892390018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2576; }","duration":"103.107648ms","start":"2026-09-15T22:22:00.002696Z","end":"2026-09-15T22:22:00.105804Z","steps":["trace[892390018] 'range keys from in-memory index tree' (duration: 102.837889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:22:36.136258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.142884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-15T22:22:36.136507Z","caller":"traceutil/trace.go:171","msg":"trace[1986872546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2770; }","duration":"381.460913ms","start":"2026-09-15T22:22:35.75502Z","end":"2026-09-15T22:22:36.136481Z","steps":["trace[1986872546] 'range keys from in-memory index tree' (duration: 380.937438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:22:36.136604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:22:35.754999Z","time spent":"381.586317ms","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-15T22:22:36.136373Z","caller":"traceutil/trace.go:171","msg":"trace[1421519727] linearizableReadLoop","detail":"{readStateIndex:2882; appliedIndex:2881; }","duration":"205.773055ms","start":"2026-09-15T22:22:35.930575Z","end":"2026-09-15T22:22:36.136348Z","steps":["trace[1421519727] 'read index received' (duration: 152.402847ms)","trace[1421519727] 'applied index is now lower than readState.Index' (duration: 53.368788ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:22:36.136982Z","caller":"traceutil/trace.go:171","msg":"trace[1346571315] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"334.163708ms","start":"2026-09-15T22:22:35.802788Z","end":"2026-09-15T22:22:36.136952Z","steps":["trace[1346571315] 'process raft request' (duration: 280.174081ms)","trace[1346571315] 'compare' (duration: 52.646787ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:22:36.137154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:22:35.802759Z","time spent":"334.27572ms","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:22:36.137329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.753484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-15T22:22:36.13739Z","caller":"traceutil/trace.go:171","msg":"trace[2008072678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2771; }","duration":"206.849097ms","start":"2026-09-15T22:22:35.930526Z","end":"2026-09-15T22:22:36.137375Z","steps":["trace[2008072678] 'agreement among raft nodes before linearized reading' (duration: 205.90435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:22:36.142028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.142504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-15T22:22:36.142242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.779655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:22:36.142315Z","caller":"traceutil/trace.go:171","msg":"trace[70904491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2772; }","duration":"138.885018ms","start":"2026-09-15T22:22:36.003411Z","end":"2026-09-15T22:22:36.142296Z","steps":["trace[70904491] 'agreement among raft nodes before linearized reading' (duration: 138.780325ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:22:36.142109Z","caller":"traceutil/trace.go:171","msg":"trace[1528864056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2772; }","duration":"210.262928ms","start":"2026-09-15T22:22:35.931831Z","end":"2026-09-15T22:22:36.142094Z","steps":["trace[1528864056] 'agreement among raft nodes before linearized reading' (duration: 210.085473ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:23:26.564633Z","caller":"traceutil/trace.go:171","msg":"trace[448037767] linearizableReadLoop","detail":"{readStateIndex:3158; appliedIndex:3157; }","duration":"128.476534ms","start":"2026-09-15T22:23:26.436131Z","end":"2026-09-15T22:23:26.564607Z","steps":["trace[448037767] 'read index received' (duration: 128.397872ms)","trace[448037767] 'applied index is now lower than readState.Index' (duration: 77.792µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:23:26.565018Z","caller":"traceutil/trace.go:171","msg":"trace[1180103429] transaction","detail":"{read_only:false; response_revision:3036; number_of_response:1; }","duration":"152.952361ms","start":"2026-09-15T22:23:26.412046Z","end":"2026-09-15T22:23:26.564998Z","steps":["trace[1180103429] 'process raft request' (duration: 152.381336ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:23:26.565468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.317615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-15T22:23:26.56551Z","caller":"traceutil/trace.go:171","msg":"trace[454754867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3036; }","duration":"129.398537ms","start":"2026-09-15T22:23:26.436101Z","end":"2026-09-15T22:23:26.5655Z","steps":["trace[454754867] 'agreement among raft nodes before linearized reading' (duration: 129.256154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:23:26.566503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.32509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-15T22:23:26.566586Z","caller":"traceutil/trace.go:171","msg":"trace[85862583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3036; }","duration":"130.447403ms","start":"2026-09-15T22:23:26.436116Z","end":"2026-09-15T22:23:26.566563Z","steps":["trace[85862583] 'agreement among raft nodes before linearized reading' (duration: 128.836872ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:23:35.071781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.103517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:23:35.072089Z","caller":"traceutil/trace.go:171","msg":"trace[1116145651] transaction","detail":"{read_only:false; response_revision:3085; number_of_response:1; }","duration":"156.535628ms","start":"2026-09-15T22:23:34.915519Z","end":"2026-09-15T22:23:35.072055Z","steps":["trace[1116145651] 'process raft request' (duration: 46.093043ms)","trace[1116145651] 'compare' (duration: 110.016875ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:23:48.624428Z","caller":"traceutil/trace.go:171","msg":"trace[172667408] transaction","detail":"{read_only:false; response_revision:3151; number_of_response:1; }","duration":"211.865724ms","start":"2026-09-15T22:23:48.412531Z","end":"2026-09-15T22:23:48.624397Z","steps":["trace[172667408] 'process raft request' (duration: 211.611407ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:23:48.624489Z","caller":"traceutil/trace.go:171","msg":"trace[1274264606] transaction","detail":"{read_only:false; response_revision:3152; number_of_response:1; }","duration":"208.772602ms","start":"2026-09-15T22:23:48.415705Z","end":"2026-09-15T22:23:48.624478Z","steps":["trace[1274264606] 'process raft request' (duration: 208.597138ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:23:48.624424Z","caller":"traceutil/trace.go:171","msg":"trace[1180414670] transaction","detail":"{read_only:false; response_revision:3153; number_of_response:1; }","duration":"189.738101ms","start":"2026-09-15T22:23:48.434659Z","end":"2026-09-15T22:23:48.624397Z","steps":["trace[1180414670] 'process raft request' (duration: 189.6933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:23:48.918111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.248893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:23:48.919296Z","caller":"traceutil/trace.go:171","msg":"trace[982885358] linearizableReadLoop","detail":"{readStateIndex:3281; appliedIndex:3280; }","duration":"204.594015ms","start":"2026-09-15T22:23:48.714631Z","end":"2026-09-15T22:23:48.919225Z","steps":["trace[982885358] 'read index received' (duration: 94.056172ms)","trace[982885358] 'applied index is now lower than readState.Index' (duration: 110.37804ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:23:48.919392Z","caller":"traceutil/trace.go:171","msg":"trace[2089797375] transaction","detail":"{read_only:false; response_revision:3154; number_of_response:1; }","duration":"248.552874ms","start":"2026-09-15T22:23:48.670777Z","end":"2026-09-15T22:23:48.91933Z","steps":["trace[2089797375] 'process raft request' (duration: 138.01584ms)","trace[2089797375] 'compare' (duration: 109.159942ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:23:48.91975Z","caller":"traceutil/trace.go:171","msg":"trace[2021261164] transaction","detail":"{read_only:false; response_revision:3155; number_of_response:1; }","duration":"102.161509ms","start":"2026-09-15T22:23:48.817559Z","end":"2026-09-15T22:23:48.91972Z","steps":["trace[2021261164] 'process raft request' (duration: 101.445842ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:23:48.920558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.433601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-15T22:23:48.920605Z","caller":"traceutil/trace.go:171","msg":"trace[1691861504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3155; }","duration":"160.522882ms","start":"2026-09-15T22:23:48.760066Z","end":"2026-09-15T22:23:48.920588Z","steps":["trace[1691861504] 'agreement among raft nodes before linearized reading' (duration: 160.245246ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:23:48.920972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.331905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-15T22:23:48.921114Z","caller":"traceutil/trace.go:171","msg":"trace[1277523665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3155; }","duration":"206.50502ms","start":"2026-09-15T22:23:48.71457Z","end":"2026-09-15T22:23:48.921075Z","steps":["trace[1277523665] 'agreement among raft nodes before linearized reading' (duration: 205.013234ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:23:49.130738Z","caller":"traceutil/trace.go:171","msg":"trace[1305045251] linearizableReadLoop","detail":"{readStateIndex:3284; appliedIndex:3282; }","duration":"201.634268ms","start":"2026-09-15T22:23:48.929075Z","end":"2026-09-15T22:23:49.130709Z","steps":["trace[1305045251] 'read index received' (duration: 140.662628ms)","trace[1305045251] 'applied index is now lower than readState.Index' (duration: 60.97021ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:23:49.130875Z","caller":"traceutil/trace.go:171","msg":"trace[1266062267] transaction","detail":"{read_only:false; response_revision:3156; number_of_response:1; }","duration":"204.316031ms","start":"2026-09-15T22:23:48.926532Z","end":"2026-09-15T22:23:49.130848Z","steps":["trace[1266062267] 'process raft request' (duration: 143.193738ms)","trace[1266062267] 'compare' (duration: 60.798826ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:23:49.130888Z","caller":"traceutil/trace.go:171","msg":"trace[782078444] transaction","detail":"{read_only:false; response_revision:3157; number_of_response:1; }","duration":"203.43847ms","start":"2026-09-15T22:23:48.927433Z","end":"2026-09-15T22:23:49.130872Z","steps":["trace[782078444] 'process raft request' (duration: 203.222815ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:23:49.131051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.650823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-15T22:23:49.131089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.882054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:23:49.131135Z","caller":"traceutil/trace.go:171","msg":"trace[1753444695] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:3157; }","duration":"121.754916ms","start":"2026-09-15T22:23:49.009359Z","end":"2026-09-15T22:23:49.131114Z","steps":["trace[1753444695] 'agreement among raft nodes before linearized reading' (duration: 121.576331ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:23:49.131154Z","caller":"traceutil/trace.go:171","msg":"trace[657921670] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:3157; }","duration":"202.07359ms","start":"2026-09-15T22:23:48.92906Z","end":"2026-09-15T22:23:49.131133Z","steps":["trace[657921670] 'agreement among raft nodes before linearized reading' (duration: 201.866174ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:23:57.301285Z","caller":"traceutil/trace.go:171","msg":"trace[607224232] transaction","detail":"{read_only:false; response_revision:3197; number_of_response:1; }","duration":"134.960144ms","start":"2026-09-15T22:23:57.166269Z","end":"2026-09-15T22:23:57.301229Z","steps":["trace[607224232] 'process raft request' (duration: 34.941271ms)","trace[607224232] 'compare' (duration: 99.808948ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:23:57.301418Z","caller":"traceutil/trace.go:171","msg":"trace[1624341860] linearizableReadLoop","detail":"{readStateIndex:3325; appliedIndex:3323; }","duration":"131.344061ms","start":"2026-09-15T22:23:57.169955Z","end":"2026-09-15T22:23:57.3013Z","steps":["trace[1624341860] 'read index received' (duration: 13.067466ms)","trace[1624341860] 'applied index is now lower than readState.Index' (duration: 118.265965ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:23:57.351114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.125807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-15T22:23:57.351194Z","caller":"traceutil/trace.go:171","msg":"trace[1214568732] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:3197; }","duration":"181.242569ms","start":"2026-09-15T22:23:57.169931Z","end":"2026-09-15T22:23:57.351173Z","steps":["trace[1214568732] 'agreement among raft nodes before linearized reading' (duration: 131.469524ms)","trace[1214568732] 'count revisions from in-memory index tree' (duration: 49.648452ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:23:57.351145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.051224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-15T22:23:57.351379Z","caller":"traceutil/trace.go:171","msg":"trace[807319290] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3197; }","duration":"154.325651ms","start":"2026-09-15T22:23:57.197023Z","end":"2026-09-15T22:23:57.351349Z","steps":["trace[807319290] 'agreement among raft nodes before linearized reading' (duration: 153.960163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:25:28.432572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9009064744355762024,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-15T22:25:28.749059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.431996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:25:28.749187Z","caller":"traceutil/trace.go:171","msg":"trace[592339060] linearizableReadLoop","detail":"{readStateIndex:3902; appliedIndex:3901; }","duration":"816.677757ms","start":"2026-09-15T22:25:27.932487Z","end":"2026-09-15T22:25:28.749165Z","steps":["trace[592339060] 'read index received' (duration: 525.040328ms)","trace[592339060] 'applied index is now lower than readState.Index' (duration: 291.635859ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:25:28.749305Z","caller":"traceutil/trace.go:171","msg":"trace[647890828] transaction","detail":"{read_only:false; response_revision:3751; number_of_response:1; }","duration":"919.838391ms","start":"2026-09-15T22:25:27.82945Z","end":"2026-09-15T22:25:28.749288Z","steps":["trace[647890828] 'process raft request' (duration: 628.047338ms)","trace[647890828] 'compare' (duration: 291.300723ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:25:28.749384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:25:27.829427Z","time spent":"919.912762ms","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:25:28.749705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.209398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-09-15T22:25:28.749748Z","caller":"traceutil/trace.go:171","msg":"trace[1135644149] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3751; }","duration":"817.285968ms","start":"2026-09-15T22:25:27.932449Z","end":"2026-09-15T22:25:28.749735Z","steps":["trace[1135644149] 'agreement among raft nodes before linearized reading' (duration: 817.135186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:25:28.749786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:25:27.93243Z","time spent":"817.34565ms","remote":"127.0.0.1:39324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":1252,"request content":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" "} {"level":"warn","ts":"2026-09-15T22:25:28.749837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.307848ms","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-15T22:25:28.749864Z","caller":"traceutil/trace.go:171","msg":"trace[1671889969] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3751; }","duration":"317.403889ms","start":"2026-09-15T22:25:28.432452Z","end":"2026-09-15T22:25:28.749856Z","steps":["trace[1671889969] 'agreement among raft nodes before linearized reading' (duration: 317.287347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:25:28.749861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.573329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-09-15T22:25:28.749864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.766432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-09-15T22:25:28.749999Z","caller":"traceutil/trace.go:171","msg":"trace[235628411] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3751; }","duration":"638.929775ms","start":"2026-09-15T22:25:28.11103Z","end":"2026-09-15T22:25:28.749959Z","steps":["trace[235628411] 'agreement among raft nodes before linearized reading' (duration: 638.662141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:25:28.750106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:25:28.111006Z","time spent":"639.062957ms","remote":"127.0.0.1:39324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":1252,"request content":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" "} {"level":"info","ts":"2026-09-15T22:25:28.749917Z","caller":"traceutil/trace.go:171","msg":"trace[329903065] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3751; }","duration":"536.674681ms","start":"2026-09-15T22:25:28.213226Z","end":"2026-09-15T22:25:28.749901Z","steps":["trace[329903065] 'agreement among raft nodes before linearized reading' (duration: 536.524689ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:25:28.749882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:25:28.432433Z","time spent":"317.444861ms","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-15T22:25:28.750236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:25:28.213207Z","time spent":"536.996427ms","remote":"127.0.0.1:38288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-15T22:26:17.454444Z","caller":"traceutil/trace.go:171","msg":"trace[1503195334] transaction","detail":"{read_only:false; response_revision:4054; number_of_response:1; }","duration":"134.492269ms","start":"2026-09-15T22:26:17.319925Z","end":"2026-09-15T22:26:17.454418Z","steps":["trace[1503195334] 'process raft request' (duration: 120.279861ms)","trace[1503195334] 'compare' (duration: 13.691938ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:26:18.22027Z","caller":"traceutil/trace.go:171","msg":"trace[46728081] transaction","detail":"{read_only:false; response_revision:4073; number_of_response:1; }","duration":"113.736187ms","start":"2026-09-15T22:26:18.106505Z","end":"2026-09-15T22:26:18.220241Z","steps":["trace[46728081] 'process raft request' (duration: 37.42384ms)","trace[46728081] 'compare' (duration: 76.164063ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:27:09.663715Z","caller":"traceutil/trace.go:171","msg":"trace[988343932] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"113.335941ms","start":"2026-09-15T22:27:09.550354Z","end":"2026-09-15T22:27:09.66369Z","steps":["trace[988343932] 'process raft request' (duration: 113.165238ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:09.663848Z","caller":"traceutil/trace.go:171","msg":"trace[1822764707] linearizableReadLoop","detail":"{readStateIndex:5013; appliedIndex:5011; }","duration":"113.314952ms","start":"2026-09-15T22:27:09.550504Z","end":"2026-09-15T22:27:09.663819Z","steps":["trace[1822764707] 'read index received' (duration: 98.357375ms)","trace[1822764707] 'applied index is now lower than readState.Index' (duration: 14.956127ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:27:09.664009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.499195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7465"} {"level":"info","ts":"2026-09-15T22:27:09.664032Z","caller":"traceutil/trace.go:171","msg":"trace[567400827] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4825; }","duration":"113.565475ms","start":"2026-09-15T22:27:09.550458Z","end":"2026-09-15T22:27:09.664024Z","steps":["trace[567400827] 'agreement among raft nodes before linearized reading' (duration: 113.418413ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:17.821069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.783107ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-15T22:27:17.8213Z","caller":"traceutil/trace.go:171","msg":"trace[1404175441] linearizableReadLoop","detail":"{readStateIndex:5144; appliedIndex:5143; }","duration":"275.515711ms","start":"2026-09-15T22:27:17.54577Z","end":"2026-09-15T22:27:17.821286Z","steps":["trace[1404175441] 'read index received' (duration: 105.41854ms)","trace[1404175441] 'applied index is now lower than readState.Index' (duration: 170.095851ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:27:17.82149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.710084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-15T22:27:17.82152Z","caller":"traceutil/trace.go:171","msg":"trace[1566095806] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4949; }","duration":"275.780706ms","start":"2026-09-15T22:27:17.545732Z","end":"2026-09-15T22:27:17.821513Z","steps":["trace[1566095806] 'agreement among raft nodes before linearized reading' (duration: 275.640394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:17.859812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.845113ms","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-15T22:27:17.85987Z","caller":"traceutil/trace.go:171","msg":"trace[1085934305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4952; }","duration":"293.945254ms","start":"2026-09-15T22:27:17.565911Z","end":"2026-09-15T22:27:17.859857Z","steps":["trace[1085934305] 'agreement among raft nodes before linearized reading' (duration: 293.782011ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:17.860231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.282357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:27:17.860258Z","caller":"traceutil/trace.go:171","msg":"trace[496643663] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:4952; }","duration":"267.375908ms","start":"2026-09-15T22:27:17.592875Z","end":"2026-09-15T22:27:17.860251Z","steps":["trace[496643663] 'agreement among raft nodes before linearized reading' (duration: 267.332897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:18.456924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.995031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:27:18.457052Z","caller":"traceutil/trace.go:171","msg":"trace[933657307] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"383.368426ms","start":"2026-09-15T22:27:18.073659Z","end":"2026-09-15T22:27:18.457027Z","steps":["trace[933657307] 'process raft request' (duration: 75.188893ms)","trace[933657307] 'compare' (duration: 307.805088ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:27:18.457135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:27:18.073484Z","time spent":"383.611801ms","remote":"127.0.0.1:38186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:27:18.513281Z","caller":"traceutil/trace.go:171","msg":"trace[1101048062] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"418.645204ms","start":"2026-09-15T22:27:18.094609Z","end":"2026-09-15T22:27:18.513254Z","steps":["trace[1101048062] 'process raft request' (duration: 418.34919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:18.513434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:27:18.094594Z","time spent":"418.768387ms","remote":"127.0.0.1:38378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:27:18.513498Z","caller":"traceutil/trace.go:171","msg":"trace[1825111318] linearizableReadLoop","detail":"{readStateIndex:5156; appliedIndex:5153; }","duration":"418.850428ms","start":"2026-09-15T22:27:18.094631Z","end":"2026-09-15T22:27:18.513482Z","steps":["trace[1825111318] 'read index received' (duration: 54.240499ms)","trace[1825111318] 'applied index is now lower than readState.Index' (duration: 364.608629ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:27:18.513651Z","caller":"traceutil/trace.go:171","msg":"trace[1226533880] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"417.833351ms","start":"2026-09-15T22:27:18.095798Z","end":"2026-09-15T22:27:18.513632Z","steps":["trace[1226533880] 'process raft request' (duration: 417.591027ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:18.513734Z","caller":"traceutil/trace.go:171","msg":"trace[1994466047] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"419.098742ms","start":"2026-09-15T22:27:18.094623Z","end":"2026-09-15T22:27:18.513722Z","steps":["trace[1994466047] 'process raft request' (duration: 418.459462ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:18.513733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:27:18.095787Z","time spent":"417.900822ms","remote":"127.0.0.1:38272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:27:18.513747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.101352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-15T22:27:18.513793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:27:18.094615Z","time spent":"419.144502ms","remote":"127.0.0.1:38378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:27:18.513795Z","caller":"traceutil/trace.go:171","msg":"trace[1408415739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4963; }","duration":"419.171453ms","start":"2026-09-15T22:27:18.094609Z","end":"2026-09-15T22:27:18.51378Z","steps":["trace[1408415739] 'agreement among raft nodes before linearized reading' (duration: 418.98793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:18.513845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:27:18.094519Z","time spent":"419.313165ms","remote":"127.0.0.1:38364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-15T22:27:18.513845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.006367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-15T22:27:18.513916Z","caller":"traceutil/trace.go:171","msg":"trace[1642729643] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:4963; }","duration":"184.112518ms","start":"2026-09-15T22:27:18.329786Z","end":"2026-09-15T22:27:18.513899Z","steps":["trace[1642729643] 'agreement among raft nodes before linearized reading' (duration: 183.962906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:18.514431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.129765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-15T22:27:18.514481Z","caller":"traceutil/trace.go:171","msg":"trace[232661172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4963; }","duration":"199.204366ms","start":"2026-09-15T22:27:18.315245Z","end":"2026-09-15T22:27:18.514469Z","steps":["trace[232661172] 'agreement among raft nodes before linearized reading' (duration: 198.418513ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:18.515928Z","caller":"traceutil/trace.go:171","msg":"trace[682569771] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"420.930243ms","start":"2026-09-15T22:27:18.094968Z","end":"2026-09-15T22:27:18.515898Z","steps":["trace[682569771] 'process raft request' (duration: 418.371911ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:18.51609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:27:18.09496Z","time spent":"421.048754ms","remote":"127.0.0.1:38272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:27:18.781141Z","caller":"traceutil/trace.go:171","msg":"trace[1498871309] linearizableReadLoop","detail":"{readStateIndex:5160; appliedIndex:5158; }","duration":"106.181192ms","start":"2026-09-15T22:27:18.674938Z","end":"2026-09-15T22:27:18.78112Z","steps":["trace[1498871309] 'read index received' (duration: 40.664378ms)","trace[1498871309] 'applied index is now lower than readState.Index' (duration: 65.515993ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:27:18.781171Z","caller":"traceutil/trace.go:171","msg":"trace[102265748] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"261.131792ms","start":"2026-09-15T22:27:18.520016Z","end":"2026-09-15T22:27:18.781148Z","steps":["trace[102265748] 'process raft request' (duration: 261.01973ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:18.781188Z","caller":"traceutil/trace.go:171","msg":"trace[1092573645] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"262.016387ms","start":"2026-09-15T22:27:18.51913Z","end":"2026-09-15T22:27:18.781146Z","steps":["trace[1092573645] 'process raft request' (duration: 196.540484ms)","trace[1092573645] 'compare' (duration: 65.108877ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:27:18.781561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.608979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-15T22:27:18.781606Z","caller":"traceutil/trace.go:171","msg":"trace[531670272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4965; }","duration":"106.701221ms","start":"2026-09-15T22:27:18.674892Z","end":"2026-09-15T22:27:18.781594Z","steps":["trace[531670272] 'agreement among raft nodes before linearized reading' (duration: 106.354004ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:18.933529Z","caller":"traceutil/trace.go:171","msg":"trace[461640781] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"121.570133ms","start":"2026-09-15T22:27:18.811932Z","end":"2026-09-15T22:27:18.933502Z","steps":["trace[461640781] 'process raft request' (duration: 121.429561ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:20.165811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.004825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:27:20.165897Z","caller":"traceutil/trace.go:171","msg":"trace[424147252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5034; }","duration":"164.132687ms","start":"2026-09-15T22:27:20.001746Z","end":"2026-09-15T22:27:20.165879Z","steps":["trace[424147252] 'range keys from in-memory index tree' (duration: 163.933164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:22.415654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.940887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-15T22:27:22.415749Z","caller":"traceutil/trace.go:171","msg":"trace[170044974] range","detail":"{range_begin:/registry/masterleases/162.253.55.223; range_end:; response_count:1; response_revision:5053; }","duration":"225.0958ms","start":"2026-09-15T22:27:22.190626Z","end":"2026-09-15T22:27:22.415722Z","steps":["trace[170044974] 'range keys from in-memory index tree' (duration: 224.687583ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:24.766356Z","caller":"traceutil/trace.go:171","msg":"trace[1085479706] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"148.626305ms","start":"2026-09-15T22:27:24.617693Z","end":"2026-09-15T22:27:24.76632Z","steps":["trace[1085479706] 'process raft request' (duration: 148.241819ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:32.246745Z","caller":"traceutil/trace.go:171","msg":"trace[600142671] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"261.135948ms","start":"2026-09-15T22:27:31.985585Z","end":"2026-09-15T22:27:32.246721Z","steps":["trace[600142671] 'process raft request' (duration: 179.33767ms)","trace[600142671] 'compare' (duration: 81.700867ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:27:32.260482Z","caller":"traceutil/trace.go:171","msg":"trace[965999623] linearizableReadLoop","detail":"{readStateIndex:5393; appliedIndex:5391; }","duration":"232.034886ms","start":"2026-09-15T22:27:32.028429Z","end":"2026-09-15T22:27:32.260464Z","steps":["trace[965999623] 'read index received' (duration: 136.506734ms)","trace[965999623] 'applied index is now lower than readState.Index' (duration: 95.526512ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:27:32.260767Z","caller":"traceutil/trace.go:171","msg":"trace[791222949] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"246.437759ms","start":"2026-09-15T22:27:32.014317Z","end":"2026-09-15T22:27:32.260754Z","steps":["trace[791222949] 'process raft request' (duration: 246.043442ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:32.261009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.568475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-15T22:27:32.261054Z","caller":"traceutil/trace.go:171","msg":"trace[1444391002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5194; }","duration":"232.653896ms","start":"2026-09-15T22:27:32.028386Z","end":"2026-09-15T22:27:32.26104Z","steps":["trace[1444391002] 'agreement among raft nodes before linearized reading' (duration: 232.511543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:32.261518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.987173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-09-15T22:27:32.261558Z","caller":"traceutil/trace.go:171","msg":"trace[600140746] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5194; }","duration":"113.029163ms","start":"2026-09-15T22:27:32.148519Z","end":"2026-09-15T22:27:32.261548Z","steps":["trace[600140746] 'agreement among raft nodes before linearized reading' (duration: 112.911172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:32.262004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.975271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-15T22:27:32.262058Z","caller":"traceutil/trace.go:171","msg":"trace[254973937] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5194; }","duration":"233.060423ms","start":"2026-09-15T22:27:32.028982Z","end":"2026-09-15T22:27:32.262043Z","steps":["trace[254973937] 'agreement among raft nodes before linearized reading' (duration: 231.976945ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:32.470439Z","caller":"traceutil/trace.go:171","msg":"trace[1118578880] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"206.291068ms","start":"2026-09-15T22:27:32.264116Z","end":"2026-09-15T22:27:32.470407Z","steps":["trace[1118578880] 'process raft request' (duration: 206.021224ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:32.470896Z","caller":"traceutil/trace.go:171","msg":"trace[1283617445] transaction","detail":"{read_only:false; response_revision:5196; number_of_response:1; }","duration":"205.294153ms","start":"2026-09-15T22:27:32.26554Z","end":"2026-09-15T22:27:32.470834Z","steps":["trace[1283617445] 'process raft request' (duration: 204.829954ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:32.471669Z","caller":"traceutil/trace.go:171","msg":"trace[1144830546] linearizableReadLoop","detail":"{readStateIndex:5395; appliedIndex:5394; }","duration":"207.471117ms","start":"2026-09-15T22:27:32.264172Z","end":"2026-09-15T22:27:32.471644Z","steps":["trace[1144830546] 'read index received' (duration: 15.9682ms)","trace[1144830546] 'applied index is now lower than readState.Index' (duration: 191.501317ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:27:32.47197Z","caller":"traceutil/trace.go:171","msg":"trace[490214246] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"199.964825ms","start":"2026-09-15T22:27:32.271982Z","end":"2026-09-15T22:27:32.471947Z","steps":["trace[490214246] 'process raft request' (duration: 198.775705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:32.472843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.637046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2a60821f-b1bf-4de9-a7dc-465cd1d78b11\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-15T22:27:32.472937Z","caller":"traceutil/trace.go:171","msg":"trace[1145682604] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2a60821f-b1bf-4de9-a7dc-465cd1d78b11; range_end:; response_count:1; response_revision:5197; }","duration":"208.768768ms","start":"2026-09-15T22:27:32.264146Z","end":"2026-09-15T22:27:32.472915Z","steps":["trace[1145682604] 'agreement among raft nodes before linearized reading' (duration: 207.579178ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:32.480594Z","caller":"traceutil/trace.go:171","msg":"trace[1908492175] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"199.6764ms","start":"2026-09-15T22:27:32.280884Z","end":"2026-09-15T22:27:32.480561Z","steps":["trace[1908492175] 'process raft request' (duration: 199.501988ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:49.198531Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2842} {"level":"info","ts":"2026-09-15T22:27:49.278928Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2842,"took":"79.044468ms","hash":2714158412,"current-db-size-bytes":51204096,"current-db-size":"51 MB","current-db-size-in-use-bytes":27480064,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-15T22:27:49.27912Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2714158412,"revision":2842,"compact-revision":-1} {"level":"info","ts":"2026-09-15T22:28:02.488394Z","caller":"traceutil/trace.go:171","msg":"trace[395724214] transaction","detail":"{read_only:false; response_revision:5479; number_of_response:1; }","duration":"122.899588ms","start":"2026-09-15T22:28:02.365466Z","end":"2026-09-15T22:28:02.488366Z","steps":["trace[395724214] 'process raft request' (duration: 122.761385ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:28:02.488411Z","caller":"traceutil/trace.go:171","msg":"trace[127365919] transaction","detail":"{read_only:false; response_revision:5478; number_of_response:1; }","duration":"128.510627ms","start":"2026-09-15T22:28:02.359871Z","end":"2026-09-15T22:28:02.488382Z","steps":["trace[127365919] 'process raft request' (duration: 127.272347ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:28:02.491034Z","caller":"traceutil/trace.go:171","msg":"trace[1744388145] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"108.069601ms","start":"2026-09-15T22:28:02.382942Z","end":"2026-09-15T22:28:02.491011Z","steps":["trace[1744388145] 'process raft request' (duration: 107.931859ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:28:21.396383Z","caller":"traceutil/trace.go:171","msg":"trace[1131404439] transaction","detail":"{read_only:false; response_revision:5606; number_of_response:1; }","duration":"121.432643ms","start":"2026-09-15T22:28:21.274918Z","end":"2026-09-15T22:28:21.396351Z","steps":["trace[1131404439] 'process raft request' (duration: 120.841615ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:30:07.3794Z","caller":"traceutil/trace.go:171","msg":"trace[2120901492] transaction","detail":"{read_only:false; response_revision:6263; number_of_response:1; }","duration":"120.523854ms","start":"2026-09-15T22:30:07.258842Z","end":"2026-09-15T22:30:07.379366Z","steps":["trace[2120901492] 'process raft request' (duration: 29.149613ms)","trace[2120901492] 'compare' (duration: 91.183667ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:30:07.380706Z","caller":"traceutil/trace.go:171","msg":"trace[1885127630] transaction","detail":"{read_only:false; response_revision:6264; number_of_response:1; }","duration":"117.890612ms","start":"2026-09-15T22:30:07.262776Z","end":"2026-09-15T22:30:07.380666Z","steps":["trace[1885127630] 'process raft request' (duration: 117.628809ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:30:07.380621Z","caller":"traceutil/trace.go:171","msg":"trace[2021768119] linearizableReadLoop","detail":"{readStateIndex:6498; appliedIndex:6496; }","duration":"103.92213ms","start":"2026-09-15T22:30:07.276655Z","end":"2026-09-15T22:30:07.380577Z","steps":["trace[2021768119] 'read index received' (duration: 11.333592ms)","trace[2021768119] 'applied index is now lower than readState.Index' (duration: 92.587278ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:30:07.381192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.161144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:30:07.381304Z","caller":"traceutil/trace.go:171","msg":"trace[1769981550] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:6264; }","duration":"104.639432ms","start":"2026-09-15T22:30:07.276621Z","end":"2026-09-15T22:30:07.38126Z","steps":["trace[1769981550] 'agreement among raft nodes before linearized reading' (duration: 104.134085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:30:07.381344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.297281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-15T22:30:07.381401Z","caller":"traceutil/trace.go:171","msg":"trace[1611326834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6264; }","duration":"103.381843ms","start":"2026-09-15T22:30:07.278002Z","end":"2026-09-15T22:30:07.381383Z","steps":["trace[1611326834] 'agreement among raft nodes before linearized reading' (duration: 103.17752ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:32:49.204547Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5311} {"level":"info","ts":"2026-09-15T22:32:49.287066Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5311,"took":"81.459219ms","hash":2140528511,"current-db-size-bytes":51204096,"current-db-size":"51 MB","current-db-size-in-use-bytes":18518016,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-15T22:32:49.287155Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2140528511,"revision":5311,"compact-revision":2842}