{"level":"warn","ts":"2026-09-08T18:55:35.743925Z","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-08T18:55:35.74404Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.28: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.28:2380","--initial-cluster=instance=https://162.253.55.28:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.28:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.28: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-08T18:55:35.744145Z","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-08T18:55:35.744178Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.28:2380"]} {"level":"info","ts":"2026-09-08T18:55:35.744203Z","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-08T18:55:35.744924Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.28:2379"]} {"level":"info","ts":"2026-09-08T18:55:35.745006Z","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.28:2380"],"listen-peer-urls":["https://162.253.55.28:2380"],"advertise-client-urls":["https://162.253.55.28:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.28:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.28: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-08T18:55:35.750232Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.992884ms"} {"level":"info","ts":"2026-09-08T18:55:35.756671Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"328ca0518abde8c2","cluster-id":"3b57b7d6a07cbbf8"} {"level":"info","ts":"2026-09-08T18:55:35.756881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T18:55:35.756908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became follower at term 0"} {"level":"info","ts":"2026-09-08T18:55:35.756917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 328ca0518abde8c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T18:55:35.756976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became follower at term 1"} {"level":"info","ts":"2026-09-08T18:55:35.757127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 switched to configuration voters=(3642462470716385474)"} {"level":"warn","ts":"2026-09-08T18:55:35.763405Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T18:55:35.76476Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T18:55:35.766565Z","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-08T18:55:35.768495Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"328ca0518abde8c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T18:55:35.772236Z","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-08T18:55:35.772904Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"328ca0518abde8c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T18:55:35.772957Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"328ca0518abde8c2","initial-advertise-peer-urls":["https://162.253.55.28:2380"],"listen-peer-urls":["https://162.253.55.28:2380"],"advertise-client-urls":["https://162.253.55.28:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.28:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T18:55:35.77577Z","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-08T18:55:35.775843Z","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-08T18:55:35.775859Z","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-08T18:55:35.776191Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T18:55:35.776515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 switched to configuration voters=(3642462470716385474)"} {"level":"info","ts":"2026-09-08T18:55:35.776708Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3b57b7d6a07cbbf8","local-member-id":"328ca0518abde8c2","added-peer-id":"328ca0518abde8c2","added-peer-peer-urls":["https://162.253.55.28:2380"]} {"level":"info","ts":"2026-09-08T18:55:35.777318Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.28:2380"} {"level":"info","ts":"2026-09-08T18:55:35.777367Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.28:2380"} {"level":"info","ts":"2026-09-08T18:55:36.057962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T18:55:36.05806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T18:55:36.05809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 received MsgPreVoteResp from 328ca0518abde8c2 at term 1"} {"level":"info","ts":"2026-09-08T18:55:36.058115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became candidate at term 2"} {"level":"info","ts":"2026-09-08T18:55:36.058173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 received MsgVoteResp from 328ca0518abde8c2 at term 2"} {"level":"info","ts":"2026-09-08T18:55:36.058201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"328ca0518abde8c2 became leader at term 2"} {"level":"info","ts":"2026-09-08T18:55:36.058373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 328ca0518abde8c2 elected leader 328ca0518abde8c2 at term 2"} {"level":"info","ts":"2026-09-08T18:55:36.059901Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T18:55:36.061621Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"328ca0518abde8c2","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.28:2379]}","request-path":"/0/members/328ca0518abde8c2/attributes","cluster-id":"3b57b7d6a07cbbf8","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T18:55:36.063262Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T18:55:36.063311Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T18:55:36.063559Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T18:55:36.063826Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T18:55:36.065581Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3b57b7d6a07cbbf8","local-member-id":"328ca0518abde8c2","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T18:55:36.066028Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T18:55:36.066289Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T18:55:36.066859Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.28:2379"} {"level":"info","ts":"2026-09-08T18:55:36.068885Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T18:55:54.578811Z","caller":"traceutil/trace.go:171","msg":"trace[1227511527] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"119.314167ms","start":"2026-09-08T18:55:54.459475Z","end":"2026-09-08T18:55:54.578789Z","steps":["trace[1227511527] 'process raft request' (duration: 119.164744ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:56:28.082336Z","caller":"traceutil/trace.go:171","msg":"trace[2141555387] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"202.488705ms","start":"2026-09-08T18:56:27.879798Z","end":"2026-09-08T18:56:28.082287Z","steps":["trace[2141555387] 'process raft request' (duration: 171.554517ms)","trace[2141555387] 'compare' (duration: 27.210893ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T18:56:28.781368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.593735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T18:56:28.781527Z","caller":"traceutil/trace.go:171","msg":"trace[1948029035] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"470.025403ms","start":"2026-09-08T18:56:28.311477Z","end":"2026-09-08T18:56:28.781503Z","steps":["trace[1948029035] 'read index received' (duration: 73.981µs)","trace[1948029035] 'applied index is now lower than readState.Index' (duration: 469.949872ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T18:56:28.781693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.214655ms","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-08T18:56:28.781721Z","caller":"traceutil/trace.go:171","msg":"trace[1436395130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"470.292806ms","start":"2026-09-08T18:56:28.311417Z","end":"2026-09-08T18:56:28.78171Z","steps":["trace[1436395130] 'agreement among raft nodes before linearized reading' (duration: 470.136924ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:56:28.781699Z","caller":"traceutil/trace.go:171","msg":"trace[977538103] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"632.995915ms","start":"2026-09-08T18:56:28.148654Z","end":"2026-09-08T18:56:28.78165Z","steps":["trace[977538103] 'process raft request' (duration: 83.540777ms)","trace[977538103] 'compare' (duration: 548.402322ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T18:56:28.781758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T18:56:28.31139Z","time spent":"470.360137ms","remote":"127.0.0.1:40674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-08T18:56:28.781924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T18:56:28.14859Z","time spent":"633.228278ms","remote":"127.0.0.1:40880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T18:56:30.37335Z","caller":"traceutil/trace.go:171","msg":"trace[1405206382] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"131.76508ms","start":"2026-09-08T18:56:30.241554Z","end":"2026-09-08T18:56:30.373319Z","steps":["trace[1405206382] 'read index received' (duration: 131.509167ms)","trace[1405206382] 'applied index is now lower than readState.Index' (duration: 252.403µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T18:56:30.373423Z","caller":"traceutil/trace.go:171","msg":"trace[1461069265] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"140.754373ms","start":"2026-09-08T18:56:30.232641Z","end":"2026-09-08T18:56:30.373395Z","steps":["trace[1461069265] 'process raft request' (duration: 140.494889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:56:30.373565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.004103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.28\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-08T18:56:30.373608Z","caller":"traceutil/trace.go:171","msg":"trace[1356522752] range","detail":"{range_begin:/registry/masterleases/162.253.55.28; range_end:; response_count:1; response_revision:643; }","duration":"132.121975ms","start":"2026-09-08T18:56:30.241474Z","end":"2026-09-08T18:56:30.373596Z","steps":["trace[1356522752] 'agreement among raft nodes before linearized reading' (duration: 131.952513ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:56:43.748916Z","caller":"traceutil/trace.go:171","msg":"trace[199520611] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"200.091945ms","start":"2026-09-08T18:56:43.5488Z","end":"2026-09-08T18:56:43.748892Z","steps":["trace[199520611] 'process raft request' (duration: 199.941943ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:58:05.556712Z","caller":"traceutil/trace.go:171","msg":"trace[739898627] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"138.85597ms","start":"2026-09-08T18:58:05.417818Z","end":"2026-09-08T18:58:05.556674Z","steps":["trace[739898627] 'read index received' (duration: 138.624717ms)","trace[739898627] 'applied index is now lower than readState.Index' (duration: 229.523µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T18:58:05.556933Z","caller":"traceutil/trace.go:171","msg":"trace[571084214] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"148.050574ms","start":"2026-09-08T18:58:05.408859Z","end":"2026-09-08T18:58:05.556909Z","steps":["trace[571084214] 'process raft request' (duration: 147.590428ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:05.557191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.328076ms","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-08T18:58:05.557336Z","caller":"traceutil/trace.go:171","msg":"trace[144020528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1861; }","duration":"139.557868ms","start":"2026-09-08T18:58:05.417743Z","end":"2026-09-08T18:58:05.557301Z","steps":["trace[144020528] 'agreement among raft nodes before linearized reading' (duration: 139.131703ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:58:05.756401Z","caller":"traceutil/trace.go:171","msg":"trace[1888010128] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"337.65271ms","start":"2026-09-08T18:58:05.418699Z","end":"2026-09-08T18:58:05.756352Z","steps":["trace[1888010128] 'process raft request' (duration: 337.086894ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:58:05.756438Z","caller":"traceutil/trace.go:171","msg":"trace[727813712] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"323.263961ms","start":"2026-09-08T18:58:05.433149Z","end":"2026-09-08T18:58:05.756413Z","steps":["trace[727813712] 'process raft request' (duration: 323.048977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:05.757077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T18:58:05.418679Z","time spent":"337.840063ms","remote":"127.0.0.1:40674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T18:58:05.757092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T18:58:05.433112Z","time spent":"323.410302ms","remote":"127.0.0.1:40674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T18:58:06.094853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.54435ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-08T18:58:06.095195Z","caller":"traceutil/trace.go:171","msg":"trace[802393968] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1938; }","duration":"538.348129ms","start":"2026-09-08T18:58:05.556819Z","end":"2026-09-08T18:58:06.095167Z","steps":["trace[802393968] 'read index received' (duration: 199.017882ms)","trace[802393968] 'applied index is now lower than readState.Index' (duration: 339.328697ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T18:58:06.095455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.862693ms","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-08T18:58:06.095453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.218415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-08T18:58:06.095485Z","caller":"traceutil/trace.go:171","msg":"trace[1223793746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1863; }","duration":"647.936023ms","start":"2026-09-08T18:58:05.447539Z","end":"2026-09-08T18:58:06.095475Z","steps":["trace[1223793746] 'agreement among raft nodes before linearized reading' (duration: 647.857383ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:58:06.095502Z","caller":"traceutil/trace.go:171","msg":"trace[1104738464] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1863; }","duration":"551.312315ms","start":"2026-09-08T18:58:05.544173Z","end":"2026-09-08T18:58:06.095485Z","steps":["trace[1104738464] 'agreement among raft nodes before linearized reading' (duration: 551.125974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:06.095548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T18:58:05.544149Z","time spent":"551.387796ms","remote":"127.0.0.1:58552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-08T18:58:06.095504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T18:58:05.447518Z","time spent":"647.981535ms","remote":"127.0.0.1:40674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-08T18:58:06.096394Z","caller":"traceutil/trace.go:171","msg":"trace[1630977665] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"516.042421ms","start":"2026-09-08T18:58:05.580318Z","end":"2026-09-08T18:58:06.09636Z","steps":["trace[1630977665] 'process raft request' (duration: 515.885319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:06.096638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T18:58:05.580304Z","time spent":"516.205943ms","remote":"127.0.0.1:40674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T18:58:06.096437Z","caller":"traceutil/trace.go:171","msg":"trace[488246633] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"533.601518ms","start":"2026-09-08T18:58:05.562812Z","end":"2026-09-08T18:58:06.096414Z","steps":["trace[488246633] 'process raft request' (duration: 532.299953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:06.096856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T18:58:05.562775Z","time spent":"534.042782ms","remote":"127.0.0.1:40674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T18:58:06.096837Z","caller":"traceutil/trace.go:171","msg":"trace[573236888] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"335.166901ms","start":"2026-09-08T18:58:05.761612Z","end":"2026-09-08T18:58:06.096779Z","steps":["trace[573236888] 'process raft request' (duration: 334.684137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:06.097106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T18:58:05.761581Z","time spent":"335.344183ms","remote":"127.0.0.1:40674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T18:58:06.110219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.680076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d65ffe3-e356-402b-b8a3-8fcc5563f030\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-09-08T18:58:06.110288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.714291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-08T18:58:06.110324Z","caller":"traceutil/trace.go:171","msg":"trace[1612975978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1867; }","duration":"226.806591ms","start":"2026-09-08T18:58:05.883507Z","end":"2026-09-08T18:58:06.110313Z","steps":["trace[1612975978] 'agreement among raft nodes before linearized reading' (duration: 226.696939ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:58:06.110429Z","caller":"traceutil/trace.go:171","msg":"trace[138571849] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d65ffe3-e356-402b-b8a3-8fcc5563f030; range_end:; response_count:1; response_revision:1867; }","duration":"463.802557ms","start":"2026-09-08T18:58:05.64647Z","end":"2026-09-08T18:58:06.110272Z","steps":["trace[138571849] 'agreement among raft nodes before linearized reading' (duration: 463.577304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:06.110478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T18:58:05.646443Z","time spent":"464.020759ms","remote":"127.0.0.1:40590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d65ffe3-e356-402b-b8a3-8fcc5563f030\" "} {"level":"info","ts":"2026-09-08T18:58:06.110349Z","caller":"traceutil/trace.go:171","msg":"trace[1715131470] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"127.595083ms","start":"2026-09-08T18:58:05.982405Z","end":"2026-09-08T18:58:06.11Z","steps":["trace[1715131470] 'process raft request' (duration: 127.470451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:21.081912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.961041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T18:58:21.082037Z","caller":"traceutil/trace.go:171","msg":"trace[784784763] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1997; }","duration":"328.163785ms","start":"2026-09-08T18:58:20.753847Z","end":"2026-09-08T18:58:21.08201Z","steps":["trace[784784763] 'count revisions from in-memory index tree' (duration: 327.7749ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:21.082095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T18:58:20.753824Z","time spent":"328.257396ms","remote":"127.0.0.1:35414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-09-08T18:58:21.082348Z","caller":"traceutil/trace.go:171","msg":"trace[1046358687] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2078; }","duration":"210.081001ms","start":"2026-09-08T18:58:20.872233Z","end":"2026-09-08T18:58:21.082314Z","steps":["trace[1046358687] 'read index received' (duration: 188.766318ms)","trace[1046358687] 'applied index is now lower than readState.Index' (duration: 21.313213ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T18:58:21.082491Z","caller":"traceutil/trace.go:171","msg":"trace[1753054888] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"274.84591ms","start":"2026-09-08T18:58:20.807536Z","end":"2026-09-08T18:58:21.082382Z","steps":["trace[1753054888] 'process raft request' (duration: 253.554197ms)","trace[1753054888] 'compare' (duration: 21.03486ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T18:58:21.082599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.349914ms","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-08T18:58:21.082644Z","caller":"traceutil/trace.go:171","msg":"trace[385336538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1998; }","duration":"210.466324ms","start":"2026-09-08T18:58:20.872166Z","end":"2026-09-08T18:58:21.082632Z","steps":["trace[385336538] 'agreement among raft nodes before linearized reading' (duration: 210.298643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:36.12323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.032103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T18:58:36.123603Z","caller":"traceutil/trace.go:171","msg":"trace[71289630] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"192.885209ms","start":"2026-09-08T18:58:35.930687Z","end":"2026-09-08T18:58:36.123572Z","steps":["trace[71289630] 'process raft request' (duration: 64.394666ms)","trace[71289630] 'compare' (duration: 127.881787ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T18:58:36.277045Z","caller":"traceutil/trace.go:171","msg":"trace[463368911] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"148.442506ms","start":"2026-09-08T18:58:36.128578Z","end":"2026-09-08T18:58:36.277021Z","steps":["trace[463368911] 'process raft request' (duration: 104.086954ms)","trace[463368911] 'compare' (duration: 43.949385ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T18:58:36.2774Z","caller":"traceutil/trace.go:171","msg":"trace[1682837300] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"148.185605ms","start":"2026-09-08T18:58:36.129182Z","end":"2026-09-08T18:58:36.277367Z","steps":["trace[1682837300] 'process raft request' (duration: 147.752706ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:58:37.364244Z","caller":"traceutil/trace.go:171","msg":"trace[1425175584] linearizableReadLoop","detail":"{readStateIndex:2180; appliedIndex:2179; }","duration":"195.747201ms","start":"2026-09-08T18:58:37.168461Z","end":"2026-09-08T18:58:37.364208Z","steps":["trace[1425175584] 'read index received' (duration: 195.442608ms)","trace[1425175584] 'applied index is now lower than readState.Index' (duration: 303.093µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T18:58:37.364337Z","caller":"traceutil/trace.go:171","msg":"trace[517551719] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"217.632874ms","start":"2026-09-08T18:58:37.146662Z","end":"2026-09-08T18:58:37.364295Z","steps":["trace[517551719] 'process raft request' (duration: 217.268968ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:37.364592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.102886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-08T18:58:37.364641Z","caller":"traceutil/trace.go:171","msg":"trace[1495243579] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2096; }","duration":"196.241242ms","start":"2026-09-08T18:58:37.168388Z","end":"2026-09-08T18:58:37.36463Z","steps":["trace[1495243579] 'agreement among raft nodes before linearized reading' (duration: 196.024953ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:58:38.043748Z","caller":"traceutil/trace.go:171","msg":"trace[1069022401] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"110.651003ms","start":"2026-09-08T18:58:37.933075Z","end":"2026-09-08T18:58:38.043726Z","steps":["trace[1069022401] 'process raft request' (duration: 110.245695ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:58:38.307401Z","caller":"traceutil/trace.go:171","msg":"trace[1287224492] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"178.61029ms","start":"2026-09-08T18:58:38.128734Z","end":"2026-09-08T18:58:38.307344Z","steps":["trace[1287224492] 'process raft request' (duration: 80.188973ms)","trace[1287224492] 'compare' (duration: 98.151136ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T18:58:38.504084Z","caller":"traceutil/trace.go:171","msg":"trace[1016608950] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2183; }","duration":"175.986298ms","start":"2026-09-08T18:58:38.328062Z","end":"2026-09-08T18:58:38.504048Z","steps":["trace[1016608950] 'read index received' (duration: 175.790879ms)","trace[1016608950] 'applied index is now lower than readState.Index' (duration: 194.149µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T18:58:38.504252Z","caller":"traceutil/trace.go:171","msg":"trace[1566132031] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"220.024028ms","start":"2026-09-08T18:58:38.28421Z","end":"2026-09-08T18:58:38.504234Z","steps":["trace[1566132031] 'process raft request' (duration: 219.60851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:38.504578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.489209ms","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-08T18:58:38.504717Z","caller":"traceutil/trace.go:171","msg":"trace[1290873565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2100; }","duration":"176.640096ms","start":"2026-09-08T18:58:38.328019Z","end":"2026-09-08T18:58:38.504659Z","steps":["trace[1290873565] 'agreement among raft nodes before linearized reading' (duration: 176.328473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:38.506021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.069687ms","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-08T18:58:38.506089Z","caller":"traceutil/trace.go:171","msg":"trace[1769463086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2101; }","duration":"166.197553ms","start":"2026-09-08T18:58:38.339875Z","end":"2026-09-08T18:58:38.506073Z","steps":["trace[1769463086] 'agreement among raft nodes before linearized reading' (duration: 165.919652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:38.506429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.920747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-08T18:58:38.506509Z","caller":"traceutil/trace.go:171","msg":"trace[1833718688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2101; }","duration":"136.064813ms","start":"2026-09-08T18:58:38.370425Z","end":"2026-09-08T18:58:38.50649Z","steps":["trace[1833718688] 'agreement among raft nodes before linearized reading' (duration: 135.844374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:58:38.506548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.114598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-08T18:58:38.506599Z","caller":"traceutil/trace.go:171","msg":"trace[508634110] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2101; }","duration":"133.191291ms","start":"2026-09-08T18:58:38.373392Z","end":"2026-09-08T18:58:38.506583Z","steps":["trace[508634110] 'agreement among raft nodes before linearized reading' (duration: 133.066916ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:58:38.506768Z","caller":"traceutil/trace.go:171","msg":"trace[930693183] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"158.180773ms","start":"2026-09-08T18:58:38.348575Z","end":"2026-09-08T18:58:38.506755Z","steps":["trace[930693183] 'process raft request' (duration: 157.123038ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:59:51.053148Z","caller":"traceutil/trace.go:171","msg":"trace[316911131] transaction","detail":"{read_only:false; response_revision:2592; number_of_response:1; }","duration":"100.061715ms","start":"2026-09-08T18:59:50.953044Z","end":"2026-09-08T18:59:51.053105Z","steps":["trace[316911131] 'process raft request' (duration: 99.792076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T18:59:51.053311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.037834ms","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-08T18:59:51.053376Z","caller":"traceutil/trace.go:171","msg":"trace[67538710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2592; }","duration":"100.181347ms","start":"2026-09-08T18:59:50.953178Z","end":"2026-09-08T18:59:51.05336Z","steps":["trace[67538710] 'agreement among raft nodes before linearized reading' (duration: 99.946841ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:59:51.464305Z","caller":"traceutil/trace.go:171","msg":"trace[680734417] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"108.342457ms","start":"2026-09-08T18:59:51.355912Z","end":"2026-09-08T18:59:51.464255Z","steps":["trace[680734417] 'process raft request' (duration: 108.058829ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T18:59:52.48355Z","caller":"traceutil/trace.go:171","msg":"trace[750563598] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"111.921432ms","start":"2026-09-08T18:59:52.37161Z","end":"2026-09-08T18:59:52.483532Z","steps":["trace[750563598] 'process raft request' (duration: 111.776168ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T19:00:27.412646Z","caller":"traceutil/trace.go:171","msg":"trace[708836262] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"154.429814ms","start":"2026-09-08T19:00:27.25819Z","end":"2026-09-08T19:00:27.41262Z","steps":["trace[708836262] 'process raft request' (duration: 154.26609ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T19:00:27.617154Z","caller":"traceutil/trace.go:171","msg":"trace[395645248] transaction","detail":"{read_only:false; response_revision:2794; number_of_response:1; }","duration":"195.687512ms","start":"2026-09-08T19:00:27.421431Z","end":"2026-09-08T19:00:27.617118Z","steps":["trace[395645248] 'process raft request' (duration: 195.642821ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T19:00:27.617254Z","caller":"traceutil/trace.go:171","msg":"trace[309925374] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"195.989031ms","start":"2026-09-08T19:00:27.421225Z","end":"2026-09-08T19:00:27.617214Z","steps":["trace[309925374] 'process raft request' (duration: 135.439059ms)","trace[309925374] 'compare' (duration: 60.213502ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T19:00:27.61761Z","caller":"traceutil/trace.go:171","msg":"trace[1305224149] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"196.132554ms","start":"2026-09-08T19:00:27.421456Z","end":"2026-09-08T19:00:27.617588Z","steps":["trace[1305224149] 'process raft request' (duration: 195.570779ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T19:05:09.408542Z","caller":"traceutil/trace.go:171","msg":"trace[1975064208] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"106.55889ms","start":"2026-09-08T19:05:09.301051Z","end":"2026-09-08T19:05:09.40761Z","steps":["trace[1975064208] 'process raft request' (duration: 106.034762ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T19:05:15.950846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.939216ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-08T19:05:15.95111Z","caller":"traceutil/trace.go:171","msg":"trace[819017498] linearizableReadLoop","detail":"{readStateIndex:5260; appliedIndex:5259; }","duration":"252.183834ms","start":"2026-09-08T19:05:15.698893Z","end":"2026-09-08T19:05:15.951077Z","steps":["trace[819017498] 'read index received' (duration: 151.047374ms)","trace[819017498] 'applied index is now lower than readState.Index' (duration: 101.13365ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T19:05:15.951283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.379927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T19:05:15.951316Z","caller":"traceutil/trace.go:171","msg":"trace[139333845] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5064; }","duration":"252.471038ms","start":"2026-09-08T19:05:15.698832Z","end":"2026-09-08T19:05:15.951303Z","steps":["trace[139333845] 'agreement among raft nodes before linearized reading' (duration: 252.371387ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T19:05:15.951194Z","caller":"traceutil/trace.go:171","msg":"trace[924795718] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"111.833923ms","start":"2026-09-08T19:05:15.839341Z","end":"2026-09-08T19:05:15.951175Z","steps":["trace[924795718] 'process raft request' (duration: 111.653871ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T19:05:15.971256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.848977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2cf9e81a-9442-4881-8c15-be793c2c387b\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-08T19:05:15.971349Z","caller":"traceutil/trace.go:171","msg":"trace[631619823] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2cf9e81a-9442-4881-8c15-be793c2c387b; range_end:; response_count:1; response_revision:5065; }","duration":"153.986809ms","start":"2026-09-08T19:05:15.817342Z","end":"2026-09-08T19:05:15.971329Z","steps":["trace[631619823] 'agreement among raft nodes before linearized reading' (duration: 153.726016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T19:05:15.971809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.80622ms","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-08T19:05:15.971891Z","caller":"traceutil/trace.go:171","msg":"trace[1397861055] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5065; }","duration":"212.981682ms","start":"2026-09-08T19:05:15.758888Z","end":"2026-09-08T19:05:15.971869Z","steps":["trace[1397861055] 'agreement among raft nodes before linearized reading' (duration: 212.643637ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T19:05:16.35135Z","caller":"traceutil/trace.go:171","msg":"trace[25580783] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"200.943626ms","start":"2026-09-08T19:05:16.150374Z","end":"2026-09-08T19:05:16.351317Z","steps":["trace[25580783] 'process raft request' (duration: 200.770964ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T19:05:29.497056Z","caller":"traceutil/trace.go:171","msg":"trace[1298310971] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"122.921096ms","start":"2026-09-08T19:05:29.374101Z","end":"2026-09-08T19:05:29.497022Z","steps":["trace[1298310971] 'process raft request' (duration: 99.978749ms)","trace[1298310971] 'compare' (duration: 22.733405ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T19:05:29.497789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.190292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T19:05:29.497902Z","caller":"traceutil/trace.go:171","msg":"trace[1989333774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5244; }","duration":"187.392096ms","start":"2026-09-08T19:05:29.310483Z","end":"2026-09-08T19:05:29.497875Z","steps":["trace[1989333774] 'range keys from in-memory index tree' (duration: 186.182847ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T19:05:36.274144Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2839} {"level":"info","ts":"2026-09-08T19:05:36.379085Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2839,"took":"102.809725ms","hash":2968121516,"current-db-size-bytes":53166080,"current-db-size":"53 MB","current-db-size-in-use-bytes":27725824,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-08T19:05:36.379299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2968121516,"revision":2839,"compact-revision":-1} {"level":"info","ts":"2026-09-08T19:06:00.434066Z","caller":"traceutil/trace.go:171","msg":"trace[311497076] linearizableReadLoop","detail":"{readStateIndex:5757; appliedIndex:5756; }","duration":"101.772301ms","start":"2026-09-08T19:06:00.332243Z","end":"2026-09-08T19:06:00.434015Z","steps":["trace[311497076] 'read index received' (duration: 40.153911ms)","trace[311497076] 'applied index is now lower than readState.Index' (duration: 61.61583ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T19:06:00.434192Z","caller":"traceutil/trace.go:171","msg":"trace[1883114499] transaction","detail":"{read_only:false; response_revision:5546; number_of_response:1; }","duration":"115.11857ms","start":"2026-09-08T19:06:00.318972Z","end":"2026-09-08T19:06:00.434091Z","steps":["trace[1883114499] 'process raft request' (duration: 53.379988ms)","trace[1883114499] 'compare' (duration: 61.487209ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T19:06:00.434349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.061616ms","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-08T19:06:00.434392Z","caller":"traceutil/trace.go:171","msg":"trace[1076770653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5546; }","duration":"102.187917ms","start":"2026-09-08T19:06:00.332192Z","end":"2026-09-08T19:06:00.43438Z","steps":["trace[1076770653] 'agreement among raft nodes before linearized reading' (duration: 101.950673ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T19:10:36.28089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5300} {"level":"info","ts":"2026-09-08T19:10:36.374332Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5300,"took":"90.428271ms","hash":3713645736,"current-db-size-bytes":53166080,"current-db-size":"53 MB","current-db-size-in-use-bytes":18980864,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-08T19:10:36.374402Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3713645736,"revision":5300,"compact-revision":2839}