{"level":"warn","ts":"2026-09-02T01:15:58.659006Z","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-02T01:15:58.659264Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43: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.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43: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-02T01:15:58.659678Z","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-02T01:15:58.659697Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-09-02T01:15:58.659882Z","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-02T01:15:58.662072Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-09-02T01:15:58.662393Z","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":16,"max-cpu-available":16,"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.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43: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-02T01:15:58.667843Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.696668ms"} {"level":"info","ts":"2026-09-02T01:15:58.676159Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-09-02T01:15:58.676533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-09-02T01:15:58.676764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-09-02T01:15:58.676899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-02T01:15:58.676908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-09-02T01:15:58.677037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-09-02T01:15:58.691468Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-02T01:15:58.693834Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-02T01:15:58.695442Z","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-02T01:15:58.698063Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-02T01:15:58.698916Z","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-02T01:15:58.699092Z","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-02T01:15:58.699107Z","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-02T01:15:58.698293Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-02T01:15:58.700259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-09-02T01:15:58.7004Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-09-02T01:15:58.704574Z","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-02T01:15:58.704733Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-09-02T01:15:58.704755Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-09-02T01:15:58.705198Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-02T01:15:58.705269Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-02T01:15:59.178222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-09-02T01:15:59.178325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-09-02T01:15:59.178374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-09-02T01:15:59.178395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-09-02T01:15:59.178406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-09-02T01:15:59.178422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-09-02T01:15:59.178436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-09-02T01:15:59.17975Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T01:15:59.180687Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-02T01:15:59.180688Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-09-02T01:15:59.180731Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-02T01:15:59.181241Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-02T01:15:59.181295Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T01:15:59.181309Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-02T01:15:59.181485Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T01:15:59.18154Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T01:15:59.182792Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-02T01:15:59.183449Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"warn","ts":"2026-09-02T01:16:17.60527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.644469ms","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-02T01:16:17.605413Z","caller":"traceutil/trace.go:171","msg":"trace[9535161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:383; }","duration":"198.821074ms","start":"2026-09-02T01:16:17.40657Z","end":"2026-09-02T01:16:17.605391Z","steps":["trace[9535161] 'range keys from in-memory index tree' (duration: 198.501755ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:16:23.053629Z","caller":"traceutil/trace.go:171","msg":"trace[170599530] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"190.147189ms","start":"2026-09-02T01:16:22.863416Z","end":"2026-09-02T01:16:23.053564Z","steps":["trace[170599530] 'process raft request' (duration: 121.88666ms)","trace[170599530] 'compare' (duration: 68.111985ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:16:37.378955Z","caller":"traceutil/trace.go:171","msg":"trace[1584420160] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"132.637438ms","start":"2026-09-02T01:16:37.246294Z","end":"2026-09-02T01:16:37.378931Z","steps":["trace[1584420160] 'process raft request' (duration: 132.322399ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:16:37.379192Z","caller":"traceutil/trace.go:171","msg":"trace[7166922] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"113.401724ms","start":"2026-09-02T01:16:37.265775Z","end":"2026-09-02T01:16:37.379177Z","steps":["trace[7166922] 'process raft request' (duration: 113.079925ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:16:37.898902Z","caller":"traceutil/trace.go:171","msg":"trace[1535003901] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"146.851805ms","start":"2026-09-02T01:16:37.752011Z","end":"2026-09-02T01:16:37.898862Z","steps":["trace[1535003901] 'process raft request' (duration: 146.486424ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:16:37.907443Z","caller":"traceutil/trace.go:171","msg":"trace[1813750579] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"142.494227ms","start":"2026-09-02T01:16:37.764923Z","end":"2026-09-02T01:16:37.907418Z","steps":["trace[1813750579] 'process raft request' (duration: 139.713045ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:16:37.907458Z","caller":"traceutil/trace.go:171","msg":"trace[717082404] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:564; }","duration":"119.585415ms","start":"2026-09-02T01:16:37.787847Z","end":"2026-09-02T01:16:37.907432Z","steps":["trace[717082404] 'read index received' (duration: 110.633733ms)","trace[717082404] 'applied index is now lower than readState.Index' (duration: 8.950552ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:16:37.907488Z","caller":"traceutil/trace.go:171","msg":"trace[702805843] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"105.39171ms","start":"2026-09-02T01:16:37.802067Z","end":"2026-09-02T01:16:37.907459Z","steps":["trace[702805843] 'process raft request' (duration: 105.226915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:16:37.907726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.854603ms","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-02T01:16:37.907767Z","caller":"traceutil/trace.go:171","msg":"trace[1488584641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:546; }","duration":"119.956197ms","start":"2026-09-02T01:16:37.787798Z","end":"2026-09-02T01:16:37.907754Z","steps":["trace[1488584641] 'agreement among raft nodes before linearized reading' (duration: 119.771981ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:16:38.379873Z","caller":"traceutil/trace.go:171","msg":"trace[2019390719] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"100.519597ms","start":"2026-09-02T01:16:38.279319Z","end":"2026-09-02T01:16:38.379839Z","steps":["trace[2019390719] 'read index received' (duration: 99.93361ms)","trace[2019390719] 'applied index is now lower than readState.Index' (duration: 584.857µs)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:16:38.380077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.721513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:16:38.380034Z","caller":"traceutil/trace.go:171","msg":"trace[965332263] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"108.729147ms","start":"2026-09-02T01:16:38.271261Z","end":"2026-09-02T01:16:38.37999Z","steps":["trace[965332263] 'process raft request' (duration: 108.214161ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:16:38.380121Z","caller":"traceutil/trace.go:171","msg":"trace[1817410011] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:555; }","duration":"100.818786ms","start":"2026-09-02T01:16:38.279287Z","end":"2026-09-02T01:16:38.380105Z","steps":["trace[1817410011] 'agreement among raft nodes before linearized reading' (duration: 100.713093ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:16:38.380206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.819165ms","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:5"} {"level":"info","ts":"2026-09-02T01:16:38.380317Z","caller":"traceutil/trace.go:171","msg":"trace[289823478] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:555; }","duration":"100.99994ms","start":"2026-09-02T01:16:38.279291Z","end":"2026-09-02T01:16:38.380291Z","steps":["trace[289823478] 'agreement among raft nodes before linearized reading' (duration: 100.789144ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:16:45.040673Z","caller":"traceutil/trace.go:171","msg":"trace[1239928180] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"463.524667ms","start":"2026-09-02T01:16:44.577113Z","end":"2026-09-02T01:16:45.040638Z","steps":["trace[1239928180] 'process raft request' (duration: 462.751054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:16:45.041727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:16:44.577064Z","time spent":"463.751693ms","remote":"127.0.0.1:40368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:16:45.222207Z","caller":"traceutil/trace.go:171","msg":"trace[795493309] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"149.663297ms","start":"2026-09-02T01:16:45.072471Z","end":"2026-09-02T01:16:45.222135Z","steps":["trace[795493309] 'read index received' (duration: 149.42521ms)","trace[795493309] 'applied index is now lower than readState.Index' (duration: 236.407µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:16:45.222264Z","caller":"traceutil/trace.go:171","msg":"trace[1062232811] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"637.840426ms","start":"2026-09-02T01:16:44.584372Z","end":"2026-09-02T01:16:45.222212Z","steps":["trace[1062232811] 'process raft request' (duration: 637.423194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:16:45.222508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:16:44.584356Z","time spent":"638.011672ms","remote":"127.0.0.1:40490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:16:45.222537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.058138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-02T01:16:45.222678Z","caller":"traceutil/trace.go:171","msg":"trace[1426255847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"150.241424ms","start":"2026-09-02T01:16:45.072424Z","end":"2026-09-02T01:16:45.222666Z","steps":["trace[1426255847] 'agreement among raft nodes before linearized reading' (duration: 149.896474ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:16:45.480827Z","caller":"traceutil/trace.go:171","msg":"trace[938124418] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"253.872751ms","start":"2026-09-02T01:16:45.22692Z","end":"2026-09-02T01:16:45.480793Z","steps":["trace[938124418] 'process raft request' (duration: 253.789909ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:16:45.480847Z","caller":"traceutil/trace.go:171","msg":"trace[1115696812] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"362.860836ms","start":"2026-09-02T01:16:45.117931Z","end":"2026-09-02T01:16:45.480792Z","steps":["trace[1115696812] 'process raft request' (duration: 337.976457ms)","trace[1115696812] 'compare' (duration: 24.56869ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:16:45.480987Z","caller":"traceutil/trace.go:171","msg":"trace[1951089750] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"258.641041ms","start":"2026-09-02T01:16:45.222314Z","end":"2026-09-02T01:16:45.480955Z","steps":["trace[1951089750] 'read index received' (duration: 233.619848ms)","trace[1951089750] 'applied index is now lower than readState.Index' (duration: 25.017663ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:16:45.481031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:16:45.117896Z","time spent":"363.059932ms","remote":"127.0.0.1:40550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-02T01:16:45.481289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.994134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-02T01:16:45.481334Z","caller":"traceutil/trace.go:171","msg":"trace[114356440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"299.138528ms","start":"2026-09-02T01:16:45.182181Z","end":"2026-09-02T01:16:45.48132Z","steps":["trace[114356440] 'agreement among raft nodes before linearized reading' (duration: 298.922262ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:16:45.992443Z","caller":"traceutil/trace.go:171","msg":"trace[1670164594] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"505.59737ms","start":"2026-09-02T01:16:45.486821Z","end":"2026-09-02T01:16:45.992419Z","steps":["trace[1670164594] 'process raft request' (duration: 505.381764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:16:45.99267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:16:45.48672Z","time spent":"505.819007ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:16:45.992737Z","caller":"traceutil/trace.go:171","msg":"trace[1802807099] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"495.876425ms","start":"2026-09-02T01:16:45.496835Z","end":"2026-09-02T01:16:45.992711Z","steps":["trace[1802807099] 'process raft request' (duration: 495.536255ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:16:45.992778Z","caller":"traceutil/trace.go:171","msg":"trace[1898265640] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"504.977622ms","start":"2026-09-02T01:16:45.487674Z","end":"2026-09-02T01:16:45.992652Z","steps":["trace[1898265640] 'read index received' (duration: 9.339474ms)","trace[1898265640] 'applied index is now lower than readState.Index' (duration: 495.635128ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:16:45.992921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:16:45.496818Z","time spent":"496.003099ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:16:45.992951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.26622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-02T01:16:45.992997Z","caller":"traceutil/trace.go:171","msg":"trace[565548343] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:609; }","duration":"505.339923ms","start":"2026-09-02T01:16:45.487642Z","end":"2026-09-02T01:16:45.992982Z","steps":["trace[565548343] 'agreement among raft nodes before linearized reading' (duration: 505.23916ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:16:45.993042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:16:45.487626Z","time spent":"505.405804ms","remote":"127.0.0.1:40514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-09-02T01:16:46.115691Z","caller":"traceutil/trace.go:171","msg":"trace[2125050419] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"110.54171ms","start":"2026-09-02T01:16:46.005123Z","end":"2026-09-02T01:16:46.115665Z","steps":["trace[2125050419] 'read index received' (duration: 107.707897ms)","trace[2125050419] 'applied index is now lower than readState.Index' (duration: 2.832083ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:16:46.115775Z","caller":"traceutil/trace.go:171","msg":"trace[1408816863] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"116.394012ms","start":"2026-09-02T01:16:45.999356Z","end":"2026-09-02T01:16:46.11575Z","steps":["trace[1408816863] 'process raft request' (duration: 113.474327ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:16:46.115893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.720346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:16:46.115968Z","caller":"traceutil/trace.go:171","msg":"trace[1350198907] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:610; }","duration":"110.838679ms","start":"2026-09-02T01:16:46.005108Z","end":"2026-09-02T01:16:46.115947Z","steps":["trace[1350198907] 'agreement among raft nodes before linearized reading' (duration: 110.686585ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:17:47.069526Z","caller":"traceutil/trace.go:171","msg":"trace[61394974] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"119.540451ms","start":"2026-09-02T01:17:46.949938Z","end":"2026-09-02T01:17:47.069478Z","steps":["trace[61394974] 'process raft request' (duration: 100.369228ms)","trace[61394974] 'compare' (duration: 18.854923ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:18:20.708324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.477726ms","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-02T01:18:20.708443Z","caller":"traceutil/trace.go:171","msg":"trace[1623037475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1861; }","duration":"108.654621ms","start":"2026-09-02T01:18:20.599766Z","end":"2026-09-02T01:18:20.708421Z","steps":["trace[1623037475] 'range keys from in-memory index tree' (duration: 108.262739ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:18:21.321639Z","caller":"traceutil/trace.go:171","msg":"trace[699257506] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1933; }","duration":"214.325983ms","start":"2026-09-02T01:18:21.107243Z","end":"2026-09-02T01:18:21.321569Z","steps":["trace[699257506] 'read index received' (duration: 158.244656ms)","trace[699257506] 'applied index is now lower than readState.Index' (duration: 56.079957ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:18:21.32167Z","caller":"traceutil/trace.go:171","msg":"trace[1633353248] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"608.772346ms","start":"2026-09-02T01:18:20.71286Z","end":"2026-09-02T01:18:21.321632Z","steps":["trace[1633353248] 'process raft request' (duration: 552.633028ms)","trace[1633353248] 'compare' (duration: 55.804138ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:18:21.321674Z","caller":"traceutil/trace.go:171","msg":"trace[1899463857] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"386.587122ms","start":"2026-09-02T01:18:20.935011Z","end":"2026-09-02T01:18:21.321598Z","steps":["trace[1899463857] 'process raft request' (duration: 386.488399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:21.321915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:18:20.934973Z","time spent":"386.809769ms","remote":"127.0.0.1:40568","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-02T01:18:21.321935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:18:20.71283Z","time spent":"608.926481ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:18:21.322376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.098296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d5ae050b-7b7f-41a9-8cf7-f31fdd12f6fe\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-02T01:18:21.322502Z","caller":"traceutil/trace.go:171","msg":"trace[2065241055] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d5ae050b-7b7f-41a9-8cf7-f31fdd12f6fe; range_end:; response_count:1; response_revision:1864; }","duration":"215.288682ms","start":"2026-09-02T01:18:21.107192Z","end":"2026-09-02T01:18:21.32248Z","steps":["trace[2065241055] 'agreement among raft nodes before linearized reading' (duration: 214.480598ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:18:21.898675Z","caller":"traceutil/trace.go:171","msg":"trace[1655831654] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"312.836737ms","start":"2026-09-02T01:18:21.585802Z","end":"2026-09-02T01:18:21.898639Z","steps":["trace[1655831654] 'process raft request' (duration: 312.59754ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:21.899006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:18:21.585763Z","time spent":"313.020952ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:18:28.027077Z","caller":"traceutil/trace.go:171","msg":"trace[1620693579] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"106.453696ms","start":"2026-09-02T01:18:27.920594Z","end":"2026-09-02T01:18:28.027048Z","steps":["trace[1620693579] 'process raft request' (duration: 106.299292ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:18:34.817147Z","caller":"traceutil/trace.go:171","msg":"trace[1456997382] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"384.381527ms","start":"2026-09-02T01:18:34.432698Z","end":"2026-09-02T01:18:34.81708Z","steps":["trace[1456997382] 'process raft request' (duration: 384.072908ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:34.817498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:18:34.432669Z","time spent":"384.647845ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:18:34.938449Z","caller":"traceutil/trace.go:171","msg":"trace[686285691] linearizableReadLoop","detail":"{readStateIndex:2058; appliedIndex:2056; }","duration":"325.839958ms","start":"2026-09-02T01:18:34.612581Z","end":"2026-09-02T01:18:34.938421Z","steps":["trace[686285691] 'read index received' (duration: 204.404882ms)","trace[686285691] 'applied index is now lower than readState.Index' (duration: 121.434026ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:18:34.938576Z","caller":"traceutil/trace.go:171","msg":"trace[1692511581] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"403.018915ms","start":"2026-09-02T01:18:34.535496Z","end":"2026-09-02T01:18:34.938515Z","steps":["trace[1692511581] 'process raft request' (duration: 362.928037ms)","trace[1692511581] 'compare' (duration: 39.784849ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:18:34.938657Z","caller":"traceutil/trace.go:171","msg":"trace[1824361901] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"153.143446ms","start":"2026-09-02T01:18:34.785435Z","end":"2026-09-02T01:18:34.938579Z","steps":["trace[1824361901] 'process raft request' (duration: 152.92994ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:34.938855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:18:34.535455Z","time spent":"403.270152ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:18:34.938739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.135656ms","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-02T01:18:34.938976Z","caller":"traceutil/trace.go:171","msg":"trace[1596053694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1985; }","duration":"326.416585ms","start":"2026-09-02T01:18:34.612513Z","end":"2026-09-02T01:18:34.938929Z","steps":["trace[1596053694] 'agreement among raft nodes before linearized reading' (duration: 326.033133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:34.939074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:18:34.612416Z","time spent":"326.611581ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-02T01:18:38.246828Z","caller":"traceutil/trace.go:171","msg":"trace[115040309] linearizableReadLoop","detail":"{readStateIndex:2076; appliedIndex:2075; }","duration":"114.355733ms","start":"2026-09-02T01:18:38.132442Z","end":"2026-09-02T01:18:38.246798Z","steps":["trace[115040309] 'read index received' (duration: 114.076434ms)","trace[115040309] 'applied index is now lower than readState.Index' (duration: 277.939µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:18:38.24703Z","caller":"traceutil/trace.go:171","msg":"trace[1893451837] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"179.256305ms","start":"2026-09-02T01:18:38.067727Z","end":"2026-09-02T01:18:38.246984Z","steps":["trace[1893451837] 'process raft request' (duration: 178.831683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:38.247108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.655561ms","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-02T01:18:38.247156Z","caller":"traceutil/trace.go:171","msg":"trace[806602359] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2001; }","duration":"114.771685ms","start":"2026-09-02T01:18:38.13237Z","end":"2026-09-02T01:18:38.247142Z","steps":["trace[806602359] 'agreement among raft nodes before linearized reading' (duration: 114.6079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:38.247239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.947794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-02T01:18:38.247338Z","caller":"traceutil/trace.go:171","msg":"trace[1192035698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2001; }","duration":"109.089748ms","start":"2026-09-02T01:18:38.138222Z","end":"2026-09-02T01:18:38.247312Z","steps":["trace[1192035698] 'agreement among raft nodes before linearized reading' (duration: 108.781749ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:38.247309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.895712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-02T01:18:38.247419Z","caller":"traceutil/trace.go:171","msg":"trace[1058703670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2001; }","duration":"109.081538ms","start":"2026-09-02T01:18:38.138313Z","end":"2026-09-02T01:18:38.247394Z","steps":["trace[1058703670] 'agreement among raft nodes before linearized reading' (duration: 108.730648ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:18:39.210483Z","caller":"traceutil/trace.go:171","msg":"trace[1016098781] linearizableReadLoop","detail":"{readStateIndex:2083; appliedIndex:2082; }","duration":"209.248635ms","start":"2026-09-02T01:18:39.001197Z","end":"2026-09-02T01:18:39.210446Z","steps":["trace[1016098781] 'read index received' (duration: 191.629538ms)","trace[1016098781] 'applied index is now lower than readState.Index' (duration: 17.617947ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:18:39.210671Z","caller":"traceutil/trace.go:171","msg":"trace[1353550023] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"234.156995ms","start":"2026-09-02T01:18:38.976499Z","end":"2026-09-02T01:18:39.210656Z","steps":["trace[1353550023] 'process raft request' (duration: 216.341222ms)","trace[1353550023] 'compare' (duration: 17.440372ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:18:39.210952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.733969ms","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-02T01:18:39.211043Z","caller":"traceutil/trace.go:171","msg":"trace[2061110854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2008; }","duration":"209.874163ms","start":"2026-09-02T01:18:39.001146Z","end":"2026-09-02T01:18:39.21102Z","steps":["trace[2061110854] 'agreement among raft nodes before linearized reading' (duration: 209.629386ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:19:11.568894Z","caller":"traceutil/trace.go:171","msg":"trace[1994705702] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"170.947372ms","start":"2026-09-02T01:19:11.397858Z","end":"2026-09-02T01:19:11.568805Z","steps":["trace[1994705702] 'process raft request' (duration: 170.788997ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:19:11.840111Z","caller":"traceutil/trace.go:171","msg":"trace[1126852818] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2267; }","duration":"276.581669ms","start":"2026-09-02T01:19:11.563504Z","end":"2026-09-02T01:19:11.840086Z","steps":["trace[1126852818] 'read index received' (duration: 5.147101ms)","trace[1126852818] 'applied index is now lower than readState.Index' (duration: 271.433517ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:19:11.840398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.879017ms","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-02T01:19:11.840397Z","caller":"traceutil/trace.go:171","msg":"trace[1647888421] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"349.831786ms","start":"2026-09-02T01:19:11.490475Z","end":"2026-09-02T01:19:11.840307Z","steps":["trace[1647888421] 'process raft request' (duration: 349.432074ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:19:11.840447Z","caller":"traceutil/trace.go:171","msg":"trace[1591176353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2187; }","duration":"276.98188ms","start":"2026-09-02T01:19:11.56345Z","end":"2026-09-02T01:19:11.840432Z","steps":["trace[1591176353] 'agreement among raft nodes before linearized reading' (duration: 276.769723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:11.84055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.564379ms","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":"warn","ts":"2026-09-02T01:19:11.84065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:11.490454Z","time spent":"350.053352ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:19:11.840675Z","caller":"traceutil/trace.go:171","msg":"trace[1772202730] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2187; }","duration":"143.756464ms","start":"2026-09-02T01:19:11.696894Z","end":"2026-09-02T01:19:11.84065Z","steps":["trace[1772202730] 'agreement among raft nodes before linearized reading' (duration: 143.422664ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:19:12.7721Z","caller":"traceutil/trace.go:171","msg":"trace[775974049] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"380.134204ms","start":"2026-09-02T01:19:12.391938Z","end":"2026-09-02T01:19:12.772072Z","steps":["trace[775974049] 'read index received' (duration: 380.091743ms)","trace[775974049] 'applied index is now lower than readState.Index' (duration: 41.421µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:19:12.772188Z","caller":"traceutil/trace.go:171","msg":"trace[1514080393] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"406.348964ms","start":"2026-09-02T01:19:12.365774Z","end":"2026-09-02T01:19:12.772123Z","steps":["trace[1514080393] 'process raft request' (duration: 406.171648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:12.772301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.352831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-02T01:19:12.772339Z","caller":"traceutil/trace.go:171","msg":"trace[242317339] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:2189; }","duration":"380.438434ms","start":"2026-09-02T01:19:12.391891Z","end":"2026-09-02T01:19:12.77233Z","steps":["trace[242317339] 'agreement among raft nodes before linearized reading' (duration: 380.291379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:12.772361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:12.365742Z","time spent":"406.528819ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:19:12.77237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:12.391874Z","time spent":"380.488705ms","remote":"127.0.0.1:40334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"info","ts":"2026-09-02T01:19:12.934363Z","caller":"traceutil/trace.go:171","msg":"trace[1502859266] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"459.339527ms","start":"2026-09-02T01:19:12.474985Z","end":"2026-09-02T01:19:12.934324Z","steps":["trace[1502859266] 'process raft request' (duration: 432.821559ms)","trace[1502859266] 'compare' (duration: 26.25375ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:19:12.934472Z","caller":"traceutil/trace.go:171","msg":"trace[594307408] linearizableReadLoop","detail":"{readStateIndex:2272; appliedIndex:2271; }","duration":"162.276177ms","start":"2026-09-02T01:19:12.772162Z","end":"2026-09-02T01:19:12.934438Z","steps":["trace[594307408] 'read index received' (duration: 135.647326ms)","trace[594307408] 'applied index is now lower than readState.Index' (duration: 26.621741ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:19:12.934565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:12.474968Z","time spent":"459.490911ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:19:12.934581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.954414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-02T01:19:12.934587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.716807ms","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-02T01:19:12.934665Z","caller":"traceutil/trace.go:171","msg":"trace[128510903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2190; }","duration":"460.106918ms","start":"2026-09-02T01:19:12.474542Z","end":"2026-09-02T01:19:12.934649Z","steps":["trace[128510903] 'agreement among raft nodes before linearized reading' (duration: 459.909002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:12.934641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.723701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-02T01:19:12.934681Z","caller":"traceutil/trace.go:171","msg":"trace[1869699562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2190; }","duration":"460.80765ms","start":"2026-09-02T01:19:12.473855Z","end":"2026-09-02T01:19:12.934663Z","steps":["trace[1869699562] 'agreement among raft nodes before linearized reading' (duration: 460.629424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:12.934706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:12.47447Z","time spent":"460.228093ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-02T01:19:12.934725Z","caller":"traceutil/trace.go:171","msg":"trace[690025771] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2190; }","duration":"278.881676ms","start":"2026-09-02T01:19:12.655824Z","end":"2026-09-02T01:19:12.934706Z","steps":["trace[690025771] 'agreement among raft nodes before linearized reading' (duration: 278.713671ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:12.934728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:12.473787Z","time spent":"460.928973ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-02T01:19:12.934801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.966154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-02T01:19:12.934839Z","caller":"traceutil/trace.go:171","msg":"trace[1056298809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2190; }","duration":"461.062846ms","start":"2026-09-02T01:19:12.473764Z","end":"2026-09-02T01:19:12.934827Z","steps":["trace[1056298809] 'agreement among raft nodes before linearized reading' (duration: 460.827939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:12.934873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:12.473714Z","time spent":"461.150539ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-02T01:19:18.275206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.770436ms","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-02T01:19:18.275284Z","caller":"traceutil/trace.go:171","msg":"trace[894058964] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2219; }","duration":"302.89411ms","start":"2026-09-02T01:19:17.972371Z","end":"2026-09-02T01:19:18.275265Z","steps":["trace[894058964] 'range keys from in-memory index tree' (duration: 302.599331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:18.275319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:17.972347Z","time spent":"302.963762ms","remote":"127.0.0.1:47308","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-02T01:19:18.275635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.860146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:19:18.275715Z","caller":"traceutil/trace.go:171","msg":"trace[84112711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2219; }","duration":"233.99806ms","start":"2026-09-02T01:19:18.041696Z","end":"2026-09-02T01:19:18.275694Z","steps":["trace[84112711] 'range keys from in-memory index tree' (duration: 233.690111ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:19:18.869511Z","caller":"traceutil/trace.go:171","msg":"trace[288152842] linearizableReadLoop","detail":"{readStateIndex:2304; appliedIndex:2303; }","duration":"149.657717ms","start":"2026-09-02T01:19:18.719827Z","end":"2026-09-02T01:19:18.869484Z","steps":["trace[288152842] 'read index received' (duration: 149.482822ms)","trace[288152842] 'applied index is now lower than readState.Index' (duration: 173.625µs)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:19:18.869661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.816662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:19:18.8697Z","caller":"traceutil/trace.go:171","msg":"trace[835606974] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2220; }","duration":"149.914575ms","start":"2026-09-02T01:19:18.719775Z","end":"2026-09-02T01:19:18.86969Z","steps":["trace[835606974] 'agreement among raft nodes before linearized reading' (duration: 149.810612ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:19:18.869659Z","caller":"traceutil/trace.go:171","msg":"trace[803731982] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"299.790319ms","start":"2026-09-02T01:19:18.569758Z","end":"2026-09-02T01:19:18.869549Z","steps":["trace[803731982] 'process raft request' (duration: 299.548382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:18.869876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:18.569725Z","time spent":"300.058197ms","remote":"127.0.0.1:40354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:21:04.654339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.985055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-02T01:21:04.654449Z","caller":"traceutil/trace.go:171","msg":"trace[1956599765] transaction","detail":"{read_only:false; response_revision:2982; number_of_response:1; }","duration":"229.382367ms","start":"2026-09-02T01:21:04.425047Z","end":"2026-09-02T01:21:04.65443Z","steps":["trace[1956599765] 'process raft request' (duration: 88.074812ms)","trace[1956599765] 'compare' (duration: 140.674586ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:23:39.839098Z","caller":"traceutil/trace.go:171","msg":"trace[992777543] transaction","detail":"{read_only:false; response_revision:3933; number_of_response:1; }","duration":"102.227719ms","start":"2026-09-02T01:23:39.736845Z","end":"2026-09-02T01:23:39.839073Z","steps":["trace[992777543] 'process raft request' (duration: 100.712015ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:25:39.522315Z","caller":"traceutil/trace.go:171","msg":"trace[372415597] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"120.524369ms","start":"2026-09-02T01:25:39.401766Z","end":"2026-09-02T01:25:39.522291Z","steps":["trace[372415597] 'process raft request' (duration: 120.329824ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:25:39.522874Z","caller":"traceutil/trace.go:171","msg":"trace[1502067456] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"110.057192ms","start":"2026-09-02T01:25:39.412795Z","end":"2026-09-02T01:25:39.522852Z","steps":["trace[1502067456] 'process raft request' (duration: 109.913787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:25:40.979354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.608828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-02T01:25:40.979465Z","caller":"traceutil/trace.go:171","msg":"trace[1809373401] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5350; }","duration":"140.791784ms","start":"2026-09-02T01:25:40.83865Z","end":"2026-09-02T01:25:40.979442Z","steps":["trace[1809373401] 'count revisions from in-memory index tree' (duration: 140.511186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:25:40.979512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.455194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-p4vj4\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-02T01:25:40.979597Z","caller":"traceutil/trace.go:171","msg":"trace[1196134670] range","detail":"{range_begin:/registry/pods/openstack/keepalived-p4vj4; range_end:; response_count:1; response_revision:5350; }","duration":"202.576598ms","start":"2026-09-02T01:25:40.777001Z","end":"2026-09-02T01:25:40.979578Z","steps":["trace[1196134670] 'range keys from in-memory index tree' (duration: 202.30756ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:25:45.889703Z","caller":"traceutil/trace.go:171","msg":"trace[1717909507] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"108.783344ms","start":"2026-09-02T01:25:45.780877Z","end":"2026-09-02T01:25:45.889661Z","steps":["trace[1717909507] 'process raft request' (duration: 85.549222ms)","trace[1717909507] 'compare' (duration: 23.071718ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:25:56.607238Z","caller":"traceutil/trace.go:171","msg":"trace[1519514446] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"106.962861ms","start":"2026-09-02T01:25:56.500239Z","end":"2026-09-02T01:25:56.607201Z","steps":["trace[1519514446] 'process raft request' (duration: 15.698961ms)","trace[1519514446] 'compare' (duration: 90.786106ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:25:56.607675Z","caller":"traceutil/trace.go:171","msg":"trace[935119618] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"100.049248ms","start":"2026-09-02T01:25:56.507597Z","end":"2026-09-02T01:25:56.607646Z","steps":["trace[935119618] 'process raft request' (duration: 99.477171ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:25:59.237011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2952} {"level":"info","ts":"2026-09-02T01:25:59.327029Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2952,"took":"88.556681ms","hash":314542484,"current-db-size-bytes":55824384,"current-db-size":"56 MB","current-db-size-in-use-bytes":31588352,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-02T01:25:59.327086Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":314542484,"revision":2952,"compact-revision":-1} {"level":"info","ts":"2026-09-02T01:30:59.245649Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5666} {"level":"info","ts":"2026-09-02T01:30:59.336515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5666,"took":"88.826388ms","hash":2848996457,"current-db-size-bytes":55824384,"current-db-size":"56 MB","current-db-size-in-use-bytes":28266496,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-02T01:30:59.336595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2848996457,"revision":5666,"compact-revision":2952} {"level":"info","ts":"2026-09-02T01:32:33.413264Z","caller":"traceutil/trace.go:171","msg":"trace[367324586] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"118.156399ms","start":"2026-09-02T01:32:33.295058Z","end":"2026-09-02T01:32:33.413215Z","steps":["trace[367324586] 'process raft request' (duration: 45.499186ms)","trace[367324586] 'compare' (duration: 72.352504ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:32:35.785154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.854233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-02T01:32:35.785231Z","caller":"traceutil/trace.go:171","msg":"trace[1786471437] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:8747; }","duration":"184.977946ms","start":"2026-09-02T01:32:35.600236Z","end":"2026-09-02T01:32:35.785214Z","steps":["trace[1786471437] 'count revisions from in-memory index tree' (duration: 184.716739ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:32:42.524783Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002390.wal"} {"level":"info","ts":"2026-09-02T01:32:47.141532Z","caller":"traceutil/trace.go:171","msg":"trace[1491032364] transaction","detail":"{read_only:false; response_revision:8809; number_of_response:1; }","duration":"118.444499ms","start":"2026-09-02T01:32:47.02306Z","end":"2026-09-02T01:32:47.141504Z","steps":["trace[1491032364] 'process raft request' (duration: 118.278014ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:32:48.046486Z","caller":"traceutil/trace.go:171","msg":"trace[2029880346] linearizableReadLoop","detail":"{readStateIndex:9130; appliedIndex:9129; }","duration":"138.145085ms","start":"2026-09-02T01:32:47.908314Z","end":"2026-09-02T01:32:48.046459Z","steps":["trace[2029880346] 'read index received' (duration: 98.463013ms)","trace[2029880346] 'applied index is now lower than readState.Index' (duration: 39.680282ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:32:48.046553Z","caller":"traceutil/trace.go:171","msg":"trace[1144613747] transaction","detail":"{read_only:false; response_revision:8813; number_of_response:1; }","duration":"123.700982ms","start":"2026-09-02T01:32:47.922821Z","end":"2026-09-02T01:32:48.046522Z","steps":["trace[1144613747] 'process raft request' (duration: 123.508286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:32:48.087444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.099574ms","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-02T01:32:48.087552Z","caller":"traceutil/trace.go:171","msg":"trace[411645683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8813; }","duration":"179.254409ms","start":"2026-09-02T01:32:47.908272Z","end":"2026-09-02T01:32:48.087526Z","steps":["trace[411645683] 'agreement among raft nodes before linearized reading' (duration: 138.30844ms)","trace[411645683] 'range keys from in-memory index tree' (duration: 40.674271ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:32:48.499158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.095253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-02T01:32:48.499683Z","caller":"traceutil/trace.go:171","msg":"trace[624646047] transaction","detail":"{read_only:false; response_revision:8817; number_of_response:1; }","duration":"171.325266ms","start":"2026-09-02T01:32:48.328316Z","end":"2026-09-02T01:32:48.499641Z","steps":["trace[624646047] 'process raft request' (duration: 31.659047ms)","trace[624646047] 'compare' (duration: 138.966039ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:33:18.602968Z","caller":"traceutil/trace.go:171","msg":"trace[1570456098] linearizableReadLoop","detail":"{readStateIndex:9356; appliedIndex:9355; }","duration":"313.893051ms","start":"2026-09-02T01:33:18.289052Z","end":"2026-09-02T01:33:18.602945Z","steps":["trace[1570456098] 'read index received' (duration: 313.561212ms)","trace[1570456098] 'applied index is now lower than readState.Index' (duration: 330.769µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:33:18.603272Z","caller":"traceutil/trace.go:171","msg":"trace[1153913256] transaction","detail":"{read_only:false; response_revision:9029; number_of_response:1; }","duration":"314.767038ms","start":"2026-09-02T01:33:18.288492Z","end":"2026-09-02T01:33:18.603259Z","steps":["trace[1153913256] 'process raft request' (duration: 314.091408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:33:18.603375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:33:18.288457Z","time spent":"314.84951ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:33:18.603638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.580971ms","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-02T01:33:18.603677Z","caller":"traceutil/trace.go:171","msg":"trace[44838560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9029; }","duration":"314.642674ms","start":"2026-09-02T01:33:18.289024Z","end":"2026-09-02T01:33:18.603667Z","steps":["trace[44838560] 'agreement among raft nodes before linearized reading' (duration: 314.504539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:33:18.603703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:33:18.289012Z","time spent":"314.684374ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-02T01:33:18.615895Z","caller":"traceutil/trace.go:171","msg":"trace[793551632] transaction","detail":"{read_only:false; response_revision:9030; number_of_response:1; }","duration":"312.680726ms","start":"2026-09-02T01:33:18.303198Z","end":"2026-09-02T01:33:18.615879Z","steps":["trace[793551632] 'process raft request' (duration: 312.558413ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:33:18.615979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:33:18.303184Z","time spent":"312.753588ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:33:18.616132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.639154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-02T01:33:18.616209Z","caller":"traceutil/trace.go:171","msg":"trace[151929963] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:9031; }","duration":"216.790108ms","start":"2026-09-02T01:33:18.399389Z","end":"2026-09-02T01:33:18.616179Z","steps":["trace[151929963] 'agreement among raft nodes before linearized reading' (duration: 216.633443ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:33:28.997457Z","caller":"traceutil/trace.go:171","msg":"trace[237855274] transaction","detail":"{read_only:false; response_revision:9084; number_of_response:1; }","duration":"346.736684ms","start":"2026-09-02T01:33:28.650688Z","end":"2026-09-02T01:33:28.997424Z","steps":["trace[237855274] 'process raft request' (duration: 346.511487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:33:28.997638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:33:28.650674Z","time spent":"346.859907ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:33:28.997718Z","caller":"traceutil/trace.go:171","msg":"trace[1801281656] transaction","detail":"{read_only:false; response_revision:9085; number_of_response:1; }","duration":"332.175877ms","start":"2026-09-02T01:33:28.665512Z","end":"2026-09-02T01:33:28.997688Z","steps":["trace[1801281656] 'process raft request' (duration: 331.862248ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:33:28.997878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:33:28.665495Z","time spent":"332.29733ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:33:28.99789Z","caller":"traceutil/trace.go:171","msg":"trace[644950177] linearizableReadLoop","detail":"{readStateIndex:9414; appliedIndex:9411; }","duration":"323.566025ms","start":"2026-09-02T01:33:28.674305Z","end":"2026-09-02T01:33:28.997872Z","steps":["trace[644950177] 'read index received' (duration: 61.864811ms)","trace[644950177] 'applied index is now lower than readState.Index' (duration: 261.700404ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:33:28.997975Z","caller":"traceutil/trace.go:171","msg":"trace[339128251] transaction","detail":"{read_only:false; response_revision:9086; number_of_response:1; }","duration":"281.892424ms","start":"2026-09-02T01:33:28.716065Z","end":"2026-09-02T01:33:28.997958Z","steps":["trace[339128251] 'process raft request' (duration: 281.584785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:33:28.99824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.923425ms","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-02T01:33:28.998419Z","caller":"traceutil/trace.go:171","msg":"trace[2064740198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9086; }","duration":"324.035558ms","start":"2026-09-02T01:33:28.674299Z","end":"2026-09-02T01:33:28.998334Z","steps":["trace[2064740198] 'agreement among raft nodes before linearized reading' (duration: 323.693608ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:33:28.998524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:33:28.674252Z","time spent":"324.212594ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-02T01:33:29.01092Z","caller":"traceutil/trace.go:171","msg":"trace[552858290] transaction","detail":"{read_only:false; response_revision:9087; number_of_response:1; }","duration":"143.931324ms","start":"2026-09-02T01:33:28.866968Z","end":"2026-09-02T01:33:29.010899Z","steps":["trace[552858290] 'process raft request' (duration: 143.678957ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:33:29.010974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.26302ms","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-02T01:33:29.011045Z","caller":"traceutil/trace.go:171","msg":"trace[2030563329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9087; }","duration":"206.364833ms","start":"2026-09-02T01:33:28.804661Z","end":"2026-09-02T01:33:29.011025Z","steps":["trace[2030563329] 'agreement among raft nodes before linearized reading' (duration: 206.137086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:33:31.376803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.487687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rnp55\" ","response":"range_response_count:1 size:4664"} {"level":"info","ts":"2026-09-02T01:33:31.37691Z","caller":"traceutil/trace.go:171","msg":"trace[307518336] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rnp55; range_end:; response_count:1; response_revision:9107; }","duration":"149.646622ms","start":"2026-09-02T01:33:31.227243Z","end":"2026-09-02T01:33:31.37689Z","steps":["trace[307518336] 'range keys from in-memory index tree' (duration: 149.379104ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:33:31.388612Z","caller":"traceutil/trace.go:171","msg":"trace[1579021310] transaction","detail":"{read_only:false; response_revision:9108; number_of_response:1; }","duration":"153.495535ms","start":"2026-09-02T01:33:31.235072Z","end":"2026-09-02T01:33:31.388568Z","steps":["trace[1579021310] 'process raft request' (duration: 153.31897ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:34:31.085794Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"14f8f2dfdaccebdb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-02T01:34:31.094064Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-02T01:34:31.094203Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-02T01:34:45.393848Z","caller":"traceutil/trace.go:171","msg":"trace[1335816445] transaction","detail":"{read_only:false; response_revision:9871; number_of_response:1; }","duration":"123.443177ms","start":"2026-09-02T01:34:45.270377Z","end":"2026-09-02T01:34:45.39382Z","steps":["trace[1335816445] 'process raft request' (duration: 108.035695ms)","trace[1335816445] 'compare' (duration: 15.309009ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:34:59.736513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.431265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-02T01:34:59.736731Z","caller":"traceutil/trace.go:171","msg":"trace[692842625] linearizableReadLoop","detail":"{readStateIndex:10304; appliedIndex:10303; }","duration":"196.25163ms","start":"2026-09-02T01:34:59.540451Z","end":"2026-09-02T01:34:59.736702Z","steps":["trace[692842625] 'read index received' (duration: 48.571µs)","trace[692842625] 'applied index is now lower than readState.Index' (duration: 196.200969ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:34:59.736787Z","caller":"traceutil/trace.go:171","msg":"trace[1245856866] transaction","detail":"{read_only:false; response_revision:9951; number_of_response:1; }","duration":"288.999728ms","start":"2026-09-02T01:34:59.44773Z","end":"2026-09-02T01:34:59.73673Z","steps":["trace[1245856866] 'process raft request' (duration: 62.183492ms)","trace[1245856866] 'compare' (duration: 226.119146ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:34:59.737003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.54178ms","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-02T01:34:59.737071Z","caller":"traceutil/trace.go:171","msg":"trace[1187020485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9951; }","duration":"196.607312ms","start":"2026-09-02T01:34:59.540442Z","end":"2026-09-02T01:34:59.737049Z","steps":["trace[1187020485] 'agreement among raft nodes before linearized reading' (duration: 196.376135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:34:59.759982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.457544ms","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-02T01:34:59.760049Z","caller":"traceutil/trace.go:171","msg":"trace[697880794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9952; }","duration":"214.568077ms","start":"2026-09-02T01:34:59.545466Z","end":"2026-09-02T01:34:59.760034Z","steps":["trace[697880794] 'agreement among raft nodes before linearized reading' (duration: 214.228097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:34:59.760041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.020931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:34:59.76013Z","caller":"traceutil/trace.go:171","msg":"trace[1146301371] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:9952; }","duration":"129.204856ms","start":"2026-09-02T01:34:59.630902Z","end":"2026-09-02T01:34:59.760107Z","steps":["trace[1146301371] 'agreement among raft nodes before linearized reading' (duration: 129.025151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:35:00.003934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.369803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-02T01:35:00.00404Z","caller":"traceutil/trace.go:171","msg":"trace[773517709] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:9954; }","duration":"155.546327ms","start":"2026-09-02T01:34:59.848466Z","end":"2026-09-02T01:35:00.004012Z","steps":["trace[773517709] 'count revisions from in-memory index tree' (duration: 155.254229ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:35:59.252964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8004} {"level":"info","ts":"2026-09-02T01:35:59.3287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8004,"took":"73.580027ms","hash":2930279024,"current-db-size-bytes":55824384,"current-db-size":"56 MB","current-db-size-in-use-bytes":28176384,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-02T01:35:59.328767Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2930279024,"revision":8004,"compact-revision":5666} {"level":"info","ts":"2026-09-02T01:40:20.294347Z","caller":"traceutil/trace.go:171","msg":"trace[189816028] transaction","detail":"{read_only:false; response_revision:12280; number_of_response:1; }","duration":"177.139617ms","start":"2026-09-02T01:40:20.117175Z","end":"2026-09-02T01:40:20.294315Z","steps":["trace[189816028] 'process raft request' (duration: 176.91807ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:40:20.321579Z","caller":"traceutil/trace.go:171","msg":"trace[84263897] transaction","detail":"{read_only:false; response_revision:12281; number_of_response:1; }","duration":"201.988093ms","start":"2026-09-02T01:40:20.119496Z","end":"2026-09-02T01:40:20.321484Z","steps":["trace[84263897] 'process raft request' (duration: 199.945383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:40:40.11825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.938568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-02T01:40:40.118022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.486891ms","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-02T01:40:40.118349Z","caller":"traceutil/trace.go:171","msg":"trace[1491535851] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:12392; }","duration":"245.100102ms","start":"2026-09-02T01:40:39.87323Z","end":"2026-09-02T01:40:40.11833Z","steps":["trace[1491535851] 'count revisions from in-memory index tree' (duration: 244.824045ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:40:40.118394Z","caller":"traceutil/trace.go:171","msg":"trace[251067052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12392; }","duration":"158.911924ms","start":"2026-09-02T01:40:39.959459Z","end":"2026-09-02T01:40:40.118371Z","steps":["trace[251067052] 'range keys from in-memory index tree' (duration: 158.336387ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:40:40.157875Z","caller":"traceutil/trace.go:171","msg":"trace[1244535442] linearizableReadLoop","detail":"{readStateIndex:12823; appliedIndex:12822; }","duration":"116.502254ms","start":"2026-09-02T01:40:40.041348Z","end":"2026-09-02T01:40:40.15785Z","steps":["trace[1244535442] 'read index received' (duration: 116.425572ms)","trace[1244535442] 'applied index is now lower than readState.Index' (duration: 75.752µs)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:40:40.158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.642519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:40:40.158084Z","caller":"traceutil/trace.go:171","msg":"trace[1780348994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12393; }","duration":"116.740772ms","start":"2026-09-02T01:40:40.041331Z","end":"2026-09-02T01:40:40.158072Z","steps":["trace[1780348994] 'agreement among raft nodes before linearized reading' (duration: 116.619318ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:40:40.158215Z","caller":"traceutil/trace.go:171","msg":"trace[1462604012] transaction","detail":"{read_only:false; response_revision:12393; number_of_response:1; }","duration":"172.161251ms","start":"2026-09-02T01:40:39.986028Z","end":"2026-09-02T01:40:40.158189Z","steps":["trace[1462604012] 'process raft request' (duration: 171.660586ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:40:59.260925Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10373} {"level":"info","ts":"2026-09-02T01:40:59.35005Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10373,"took":"86.58884ms","hash":3712080419,"current-db-size-bytes":55824384,"current-db-size":"56 MB","current-db-size-in-use-bytes":25890816,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-02T01:40:59.350238Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3712080419,"revision":10373,"compact-revision":8004} {"level":"info","ts":"2026-09-02T01:45:59.267955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12548} {"level":"info","ts":"2026-09-02T01:45:59.353238Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12548,"took":"82.920061ms","hash":3809390848,"current-db-size-bytes":55824384,"current-db-size":"56 MB","current-db-size-in-use-bytes":28000256,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-02T01:45:59.353316Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3809390848,"revision":12548,"compact-revision":10373} {"level":"info","ts":"2026-09-02T01:47:30.778734Z","caller":"traceutil/trace.go:171","msg":"trace[391650719] transaction","detail":"{read_only:false; response_revision:15114; number_of_response:1; }","duration":"141.832523ms","start":"2026-09-02T01:47:30.63685Z","end":"2026-09-02T01:47:30.778683Z","steps":["trace[391650719] 'process raft request' (duration: 133.082767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:47:30.778763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.604692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-hdmhg\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-09-02T01:47:30.778847Z","caller":"traceutil/trace.go:171","msg":"trace[368169198] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-hdmhg; range_end:; response_count:1; response_revision:15113; }","duration":"147.741456ms","start":"2026-09-02T01:47:30.631088Z","end":"2026-09-02T01:47:30.778829Z","steps":["trace[368169198] 'range keys from in-memory index tree' (duration: 147.396016ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:47:30.795183Z","caller":"traceutil/trace.go:171","msg":"trace[1408122042] transaction","detail":"{read_only:false; response_revision:15115; number_of_response:1; }","duration":"147.22578ms","start":"2026-09-02T01:47:30.647917Z","end":"2026-09-02T01:47:30.795143Z","steps":["trace[1408122042] 'process raft request' (duration: 146.964773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:47:30.837022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.43479ms","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-02T01:47:30.837151Z","caller":"traceutil/trace.go:171","msg":"trace[1311565251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15116; }","duration":"129.585544ms","start":"2026-09-02T01:47:30.707536Z","end":"2026-09-02T01:47:30.837122Z","steps":["trace[1311565251] 'agreement among raft nodes before linearized reading' (duration: 129.307025ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:47:42.511028Z","caller":"traceutil/trace.go:171","msg":"trace[2015395587] linearizableReadLoop","detail":"{readStateIndex:15821; appliedIndex:15820; }","duration":"393.758379ms","start":"2026-09-02T01:47:42.117246Z","end":"2026-09-02T01:47:42.511004Z","steps":["trace[2015395587] 'read index received' (duration: 393.636705ms)","trace[2015395587] 'applied index is now lower than readState.Index' (duration: 120.573µs)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:47:42.511383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.124479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:416190"} {"level":"info","ts":"2026-09-02T01:47:42.511128Z","caller":"traceutil/trace.go:171","msg":"trace[1304099327] transaction","detail":"{read_only:false; response_revision:15291; number_of_response:1; }","duration":"408.015505ms","start":"2026-09-02T01:47:42.10308Z","end":"2026-09-02T01:47:42.511096Z","steps":["trace[1304099327] 'process raft request' (duration: 407.761568ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:47:42.511432Z","caller":"traceutil/trace.go:171","msg":"trace[433907324] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:15291; }","duration":"394.226812ms","start":"2026-09-02T01:47:42.11719Z","end":"2026-09-02T01:47:42.511417Z","steps":["trace[433907324] 'agreement among raft nodes before linearized reading' (duration: 393.949303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:47:42.511473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:47:42.117167Z","time spent":"394.297264ms","remote":"127.0.0.1:39880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":416212,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-09-02T01:47:42.511658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:47:42.103058Z","time spent":"408.404657ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:47:42.533838Z","caller":"traceutil/trace.go:171","msg":"trace[1194569876] transaction","detail":"{read_only:false; response_revision:15292; number_of_response:1; }","duration":"352.563842ms","start":"2026-09-02T01:47:42.181248Z","end":"2026-09-02T01:47:42.533812Z","steps":["trace[1194569876] 'process raft request' (duration: 352.14702ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:47:42.534051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:47:42.181217Z","time spent":"352.735408ms","remote":"127.0.0.1:40568","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-02T01:48:51.07172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.473355ms","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-02T01:48:51.071804Z","caller":"traceutil/trace.go:171","msg":"trace[1192731761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16212; }","duration":"106.621159ms","start":"2026-09-02T01:48:50.965164Z","end":"2026-09-02T01:48:51.071785Z","steps":["trace[1192731761] 'range keys from in-memory index tree' (duration: 106.267359ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:49:09.12534Z","caller":"traceutil/trace.go:171","msg":"trace[849807313] linearizableReadLoop","detail":"{readStateIndex:16870; appliedIndex:16869; }","duration":"179.241653ms","start":"2026-09-02T01:49:08.946075Z","end":"2026-09-02T01:49:09.125317Z","steps":["trace[849807313] 'read index received' (duration: 179.013377ms)","trace[849807313] 'applied index is now lower than readState.Index' (duration: 227.456µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:49:09.125404Z","caller":"traceutil/trace.go:171","msg":"trace[721468476] transaction","detail":"{read_only:false; response_revision:16310; number_of_response:1; }","duration":"225.699383ms","start":"2026-09-02T01:49:08.899671Z","end":"2026-09-02T01:49:09.12537Z","steps":["trace[721468476] 'process raft request' (duration: 225.479757ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:49:09.125591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.502692ms","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-02T01:49:09.125656Z","caller":"traceutil/trace.go:171","msg":"trace[582807393] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16310; }","duration":"179.616905ms","start":"2026-09-02T01:49:08.946023Z","end":"2026-09-02T01:49:09.12564Z","steps":["trace[582807393] 'agreement among raft nodes before linearized reading' (duration: 179.427369ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:49:10.380284Z","caller":"traceutil/trace.go:171","msg":"trace[1607401232] linearizableReadLoop","detail":"{readStateIndex:16877; appliedIndex:16876; }","duration":"115.995914ms","start":"2026-09-02T01:49:10.264256Z","end":"2026-09-02T01:49:10.380252Z","steps":["trace[1607401232] 'read index received' (duration: 115.734306ms)","trace[1607401232] 'applied index is now lower than readState.Index' (duration: 260.438µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:49:10.380663Z","caller":"traceutil/trace.go:171","msg":"trace[1341719413] transaction","detail":"{read_only:false; response_revision:16317; number_of_response:1; }","duration":"118.272301ms","start":"2026-09-02T01:49:10.262372Z","end":"2026-09-02T01:49:10.380644Z","steps":["trace[1341719413] 'process raft request' (duration: 117.692224ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:49:10.38103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.759966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-02T01:49:10.381092Z","caller":"traceutil/trace.go:171","msg":"trace[1333679492] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16317; }","duration":"116.875889ms","start":"2026-09-02T01:49:10.264198Z","end":"2026-09-02T01:49:10.381073Z","steps":["trace[1333679492] 'agreement among raft nodes before linearized reading' (duration: 116.689244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:49:10.711922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.184652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-02T01:49:10.712052Z","caller":"traceutil/trace.go:171","msg":"trace[1677432747] linearizableReadLoop","detail":"{readStateIndex:16878; appliedIndex:16877; }","duration":"252.514118ms","start":"2026-09-02T01:49:10.459517Z","end":"2026-09-02T01:49:10.712031Z","steps":["trace[1677432747] 'read index received' (duration: 125.027288ms)","trace[1677432747] 'applied index is now lower than readState.Index' (duration: 127.48508ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:49:10.71217Z","caller":"traceutil/trace.go:171","msg":"trace[642358798] transaction","detail":"{read_only:false; response_revision:16318; number_of_response:1; }","duration":"281.886117ms","start":"2026-09-02T01:49:10.430264Z","end":"2026-09-02T01:49:10.71215Z","steps":["trace[642358798] 'process raft request' (duration: 154.358686ms)","trace[642358798] 'compare' (duration: 126.902232ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:49:10.712272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.749195ms","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-02T01:49:10.712316Z","caller":"traceutil/trace.go:171","msg":"trace[994121472] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16318; }","duration":"252.834887ms","start":"2026-09-02T01:49:10.459469Z","end":"2026-09-02T01:49:10.712304Z","steps":["trace[994121472] 'agreement among raft nodes before linearized reading' (duration: 252.702053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:49:10.712313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.423879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-02T01:49:10.712318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.633745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-02T01:49:10.712348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.651514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-02T01:49:10.712391Z","caller":"traceutil/trace.go:171","msg":"trace[949164588] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16318; }","duration":"126.549732ms","start":"2026-09-02T01:49:10.585819Z","end":"2026-09-02T01:49:10.712369Z","steps":["trace[949164588] 'agreement among raft nodes before linearized reading' (duration: 126.422408ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:49:10.712399Z","caller":"traceutil/trace.go:171","msg":"trace[2053647846] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16318; }","duration":"115.744667ms","start":"2026-09-02T01:49:10.59664Z","end":"2026-09-02T01:49:10.712385Z","steps":["trace[2053647846] 'agreement among raft nodes before linearized reading' (duration: 115.52964ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:49:10.712407Z","caller":"traceutil/trace.go:171","msg":"trace[1970052901] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16318; }","duration":"126.768769ms","start":"2026-09-02T01:49:10.585614Z","end":"2026-09-02T01:49:10.712383Z","steps":["trace[1970052901] 'agreement among raft nodes before linearized reading' (duration: 126.560812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:49:10.712313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.380878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:49:10.712515Z","caller":"traceutil/trace.go:171","msg":"trace[387667436] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16318; }","duration":"126.616345ms","start":"2026-09-02T01:49:10.585877Z","end":"2026-09-02T01:49:10.712493Z","steps":["trace[387667436] 'agreement among raft nodes before linearized reading' (duration: 126.300976ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:50:59.274789Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14550} {"level":"info","ts":"2026-09-02T01:50:59.364411Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14550,"took":"87.101678ms","hash":1574806481,"current-db-size-bytes":55824384,"current-db-size":"56 MB","current-db-size-in-use-bytes":31657984,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-02T01:50:59.364497Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1574806481,"revision":14550,"compact-revision":12548} {"level":"info","ts":"2026-09-02T01:55:00.49906Z","caller":"traceutil/trace.go:171","msg":"trace[487655744] transaction","detail":"{read_only:false; response_revision:18796; number_of_response:1; }","duration":"101.612653ms","start":"2026-09-02T01:55:00.397392Z","end":"2026-09-02T01:55:00.499005Z","steps":["trace[487655744] 'process raft request' (duration: 101.370476ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:55:00.629495Z","caller":"traceutil/trace.go:171","msg":"trace[205598206] transaction","detail":"{read_only:false; response_revision:18797; number_of_response:1; }","duration":"158.308121ms","start":"2026-09-02T01:55:00.471013Z","end":"2026-09-02T01:55:00.629321Z","steps":["trace[205598206] 'process raft request' (duration: 158.129676ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:55:00.630148Z","caller":"traceutil/trace.go:171","msg":"trace[1494916125] transaction","detail":"{read_only:false; response_revision:18799; number_of_response:1; }","duration":"129.406736ms","start":"2026-09-02T01:55:00.500715Z","end":"2026-09-02T01:55:00.630122Z","steps":["trace[1494916125] 'process raft request' (duration: 129.359644ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:55:00.630234Z","caller":"traceutil/trace.go:171","msg":"trace[549647645] transaction","detail":"{read_only:false; response_revision:18798; number_of_response:1; }","duration":"131.488287ms","start":"2026-09-02T01:55:00.498713Z","end":"2026-09-02T01:55:00.630201Z","steps":["trace[549647645] 'process raft request' (duration: 131.211889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:55:03.00145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.626177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T01:55:03.001545Z","caller":"traceutil/trace.go:171","msg":"trace[821972561] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:18810; }","duration":"218.75067ms","start":"2026-09-02T01:55:02.782774Z","end":"2026-09-02T01:55:03.001524Z","steps":["trace[821972561] 'count revisions from in-memory index tree' (duration: 218.488033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:55:03.001656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.479424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7382"} {"level":"info","ts":"2026-09-02T01:55:03.001743Z","caller":"traceutil/trace.go:171","msg":"trace[1054313749] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:18810; }","duration":"216.608388ms","start":"2026-09-02T01:55:02.785109Z","end":"2026-09-02T01:55:03.001718Z","steps":["trace[1054313749] 'range keys from in-memory index tree' (duration: 216.3399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:55:07.532939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.087289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T01:55:07.533024Z","caller":"traceutil/trace.go:171","msg":"trace[828976697] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:18838; }","duration":"127.230043ms","start":"2026-09-02T01:55:07.405771Z","end":"2026-09-02T01:55:07.533001Z","steps":["trace[828976697] 'count revisions from in-memory index tree' (duration: 126.937644ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:55:24.531534Z","caller":"traceutil/trace.go:171","msg":"trace[1025753195] transaction","detail":"{read_only:false; response_revision:19067; number_of_response:1; }","duration":"188.044338ms","start":"2026-09-02T01:55:24.343454Z","end":"2026-09-02T01:55:24.531498Z","steps":["trace[1025753195] 'process raft request' (duration: 187.812191ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:55:54.908245Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"14f8f2dfdaccebdb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-02T01:55:54.915262Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-02T01:55:54.915422Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-02T01:55:59.282414Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17006} {"level":"info","ts":"2026-09-02T01:55:59.384086Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17006,"took":"98.154995ms","hash":705214912,"current-db-size-bytes":55824384,"current-db-size":"56 MB","current-db-size-in-use-bytes":33062912,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-02T01:55:59.384134Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":705214912,"revision":17006,"compact-revision":14550} {"level":"info","ts":"2026-09-02T01:57:14.410571Z","caller":"traceutil/trace.go:171","msg":"trace[1145590642] transaction","detail":"{read_only:false; response_revision:20088; number_of_response:1; }","duration":"111.054103ms","start":"2026-09-02T01:57:14.299486Z","end":"2026-09-02T01:57:14.41054Z","steps":["trace[1145590642] 'process raft request' (duration: 110.897268ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:00:59.289456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19398} {"level":"info","ts":"2026-09-02T02:00:59.410289Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19398,"took":"117.292067ms","hash":3202307640,"current-db-size-bytes":55824384,"current-db-size":"56 MB","current-db-size-in-use-bytes":30511104,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-02T02:00:59.410349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3202307640,"revision":19398,"compact-revision":17006} {"level":"warn","ts":"2026-09-02T02:01:36.176306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.351157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T02:01:36.17642Z","caller":"traceutil/trace.go:171","msg":"trace[641589710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21893; }","duration":"134.531762ms","start":"2026-09-02T02:01:36.041867Z","end":"2026-09-02T02:01:36.176398Z","steps":["trace[641589710] 'range keys from in-memory index tree' (duration: 134.262664ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:36.274634Z","caller":"traceutil/trace.go:171","msg":"trace[1212547514] linearizableReadLoop","detail":"{readStateIndex:22639; appliedIndex:22636; }","duration":"104.617326ms","start":"2026-09-02T02:01:36.169982Z","end":"2026-09-02T02:01:36.274599Z","steps":["trace[1212547514] 'read index received' (duration: 26.437585ms)","trace[1212547514] 'applied index is now lower than readState.Index' (duration: 78.178661ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T02:01:36.274664Z","caller":"traceutil/trace.go:171","msg":"trace[719400709] transaction","detail":"{read_only:false; response_revision:21895; number_of_response:1; }","duration":"136.240703ms","start":"2026-09-02T02:01:36.13839Z","end":"2026-09-02T02:01:36.274631Z","steps":["trace[719400709] 'process raft request' (duration: 136.007046ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:36.274779Z","caller":"traceutil/trace.go:171","msg":"trace[1658066549] transaction","detail":"{read_only:false; response_revision:21896; number_of_response:1; }","duration":"108.209441ms","start":"2026-09-02T02:01:36.166521Z","end":"2026-09-02T02:01:36.27473Z","steps":["trace[1658066549] 'process raft request' (duration: 108.018845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:36.274842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.846142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-02T02:01:36.274893Z","caller":"traceutil/trace.go:171","msg":"trace[1367558298] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:21896; }","duration":"104.950115ms","start":"2026-09-02T02:01:36.169926Z","end":"2026-09-02T02:01:36.274876Z","steps":["trace[1367558298] 'agreement among raft nodes before linearized reading' (duration: 104.838512ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:36.403362Z","caller":"traceutil/trace.go:171","msg":"trace[25677721] transaction","detail":"{read_only:false; response_revision:21897; number_of_response:1; }","duration":"124.359544ms","start":"2026-09-02T02:01:36.278975Z","end":"2026-09-02T02:01:36.403335Z","steps":["trace[25677721] 'process raft request' (duration: 124.2051ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:41.857347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.340953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T02:01:41.857498Z","caller":"traceutil/trace.go:171","msg":"trace[785172919] transaction","detail":"{read_only:false; response_revision:21921; number_of_response:1; }","duration":"371.029322ms","start":"2026-09-02T02:01:41.48645Z","end":"2026-09-02T02:01:41.857479Z","steps":["trace[785172919] 'process raft request' (duration: 260.478342ms)","trace[785172919] 'compare' (duration: 110.2271ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T02:01:41.857557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T02:01:41.486422Z","time spent":"371.105054ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T02:01:42.127559Z","caller":"traceutil/trace.go:171","msg":"trace[1752238358] transaction","detail":"{read_only:false; response_revision:21923; number_of_response:1; }","duration":"264.963239ms","start":"2026-09-02T02:01:41.862566Z","end":"2026-09-02T02:01:42.127529Z","steps":["trace[1752238358] 'process raft request' (duration: 254.884836ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:42.207757Z","caller":"traceutil/trace.go:171","msg":"trace[1940102225] linearizableReadLoop","detail":"{readStateIndex:22668; appliedIndex:22666; }","duration":"166.90649ms","start":"2026-09-02T02:01:42.040828Z","end":"2026-09-02T02:01:42.207735Z","steps":["trace[1940102225] 'read index received' (duration: 76.73818ms)","trace[1940102225] 'applied index is now lower than readState.Index' (duration: 90.16734ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T02:01:42.207919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.058924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T02:01:42.207956Z","caller":"traceutil/trace.go:171","msg":"trace[757607591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21924; }","duration":"167.172457ms","start":"2026-09-02T02:01:42.040773Z","end":"2026-09-02T02:01:42.207946Z","steps":["trace[757607591] 'agreement among raft nodes before linearized reading' (duration: 167.073494ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:42.207928Z","caller":"traceutil/trace.go:171","msg":"trace[537732301] transaction","detail":"{read_only:false; response_revision:21924; number_of_response:1; }","duration":"252.050984ms","start":"2026-09-02T02:01:41.955839Z","end":"2026-09-02T02:01:42.20789Z","steps":["trace[537732301] 'process raft request' (duration: 251.769565ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:42.448721Z","caller":"traceutil/trace.go:171","msg":"trace[401210413] transaction","detail":"{read_only:false; response_revision:21925; number_of_response:1; }","duration":"223.894526ms","start":"2026-09-02T02:01:42.224807Z","end":"2026-09-02T02:01:42.448702Z","steps":["trace[401210413] 'process raft request' (duration: 223.778402ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:42.489503Z","caller":"traceutil/trace.go:171","msg":"trace[949961111] transaction","detail":"{read_only:false; response_revision:21926; number_of_response:1; }","duration":"195.436869ms","start":"2026-09-02T02:01:42.294039Z","end":"2026-09-02T02:01:42.489475Z","steps":["trace[949961111] 'process raft request' (duration: 195.229413ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:42.489771Z","caller":"traceutil/trace.go:171","msg":"trace[389430824] transaction","detail":"{read_only:false; response_revision:21927; number_of_response:1; }","duration":"195.652365ms","start":"2026-09-02T02:01:42.294074Z","end":"2026-09-02T02:01:42.489726Z","steps":["trace[389430824] 'process raft request' (duration: 195.356366ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:42.809357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.891139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T02:01:42.809459Z","caller":"traceutil/trace.go:171","msg":"trace[272436899] linearizableReadLoop","detail":"{readStateIndex:22672; appliedIndex:22671; }","duration":"266.077841ms","start":"2026-09-02T02:01:42.543365Z","end":"2026-09-02T02:01:42.809443Z","steps":["trace[272436899] 'read index received' (duration: 130.912814ms)","trace[272436899] 'applied index is now lower than readState.Index' (duration: 135.163507ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T02:01:42.809552Z","caller":"traceutil/trace.go:171","msg":"trace[665645559] transaction","detail":"{read_only:false; response_revision:21928; number_of_response:1; }","duration":"314.874689ms","start":"2026-09-02T02:01:42.494667Z","end":"2026-09-02T02:01:42.809541Z","steps":["trace[665645559] 'process raft request' (duration: 179.718192ms)","trace[665645559] 'compare' (duration: 134.646132ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T02:01:42.809611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T02:01:42.494638Z","time spent":"314.942481ms","remote":"127.0.0.1:40568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T02:01:42.809812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.173523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T02:01:42.809877Z","caller":"traceutil/trace.go:171","msg":"trace[710469754] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:21928; }","duration":"161.282856ms","start":"2026-09-02T02:01:42.648579Z","end":"2026-09-02T02:01:42.809861Z","steps":["trace[710469754] 'agreement among raft nodes before linearized reading' (duration: 161.175503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:42.810152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.788732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-02T02:01:42.810213Z","caller":"traceutil/trace.go:171","msg":"trace[1332984385] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:21928; }","duration":"266.883995ms","start":"2026-09-02T02:01:42.543316Z","end":"2026-09-02T02:01:42.8102Z","steps":["trace[1332984385] 'agreement among raft nodes before linearized reading' (duration: 266.514404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:43.269889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.578555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-02T02:01:43.270066Z","caller":"traceutil/trace.go:171","msg":"trace[804241688] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21929; }","duration":"193.799231ms","start":"2026-09-02T02:01:43.076249Z","end":"2026-09-02T02:01:43.270048Z","steps":["trace[804241688] 'range keys from in-memory index tree' (duration: 193.40014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:43.269895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.291705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-02T02:01:43.270222Z","caller":"traceutil/trace.go:171","msg":"trace[953080978] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:21929; }","duration":"333.664376ms","start":"2026-09-02T02:01:42.936535Z","end":"2026-09-02T02:01:43.2702Z","steps":["trace[953080978] 'count revisions from in-memory index tree' (duration: 333.16075ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:43.270282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T02:01:42.936516Z","time spent":"333.748067ms","remote":"127.0.0.1:40368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":199,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-09-02T02:01:43.27068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.857511ms","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-02T02:01:43.270732Z","caller":"traceutil/trace.go:171","msg":"trace[1413975384] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21929; }","duration":"195.964495ms","start":"2026-09-02T02:01:43.074751Z","end":"2026-09-02T02:01:43.270715Z","steps":["trace[1413975384] 'range keys from in-memory index tree' (duration: 195.341136ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:43.432755Z","caller":"traceutil/trace.go:171","msg":"trace[2132935758] linearizableReadLoop","detail":"{readStateIndex:22675; appliedIndex:22674; }","duration":"157.60085ms","start":"2026-09-02T02:01:43.275129Z","end":"2026-09-02T02:01:43.43273Z","steps":["trace[2132935758] 'read index received' (duration: 157.24215ms)","trace[2132935758] 'applied index is now lower than readState.Index' (duration: 357.5µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T02:01:43.432833Z","caller":"traceutil/trace.go:171","msg":"trace[1318445772] transaction","detail":"{read_only:false; response_revision:21930; number_of_response:1; }","duration":"158.2731ms","start":"2026-09-02T02:01:43.27451Z","end":"2026-09-02T02:01:43.432783Z","steps":["trace[1318445772] 'process raft request' (duration: 157.967341ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:43.433042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.894588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-02T02:01:43.433083Z","caller":"traceutil/trace.go:171","msg":"trace[1531610049] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21930; }","duration":"157.988931ms","start":"2026-09-02T02:01:43.275084Z","end":"2026-09-02T02:01:43.433073Z","steps":["trace[1531610049] 'agreement among raft nodes before linearized reading' (duration: 157.783865ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:43.576815Z","caller":"traceutil/trace.go:171","msg":"trace[521759067] transaction","detail":"{read_only:false; response_revision:21931; number_of_response:1; }","duration":"139.332999ms","start":"2026-09-02T02:01:43.437448Z","end":"2026-09-02T02:01:43.576781Z","steps":["trace[521759067] 'process raft request' (duration: 138.442923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:05:53.413896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.95911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T02:05:53.414011Z","caller":"traceutil/trace.go:171","msg":"trace[860879012] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:24482; }","duration":"166.139505ms","start":"2026-09-02T02:05:53.247849Z","end":"2026-09-02T02:05:53.413988Z","steps":["trace[860879012] 'count revisions from in-memory index tree' (duration: 165.858037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:05:53.414035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.818182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-02T02:05:53.414123Z","caller":"traceutil/trace.go:171","msg":"trace[470824322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24482; }","duration":"105.961917ms","start":"2026-09-02T02:05:53.308139Z","end":"2026-09-02T02:05:53.414101Z","steps":["trace[470824322] 'range keys from in-memory index tree' (duration: 105.554475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:05:53.414399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.262537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-02T02:05:53.414512Z","caller":"traceutil/trace.go:171","msg":"trace[352564120] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:24482; }","duration":"182.469843ms","start":"2026-09-02T02:05:53.232014Z","end":"2026-09-02T02:05:53.414484Z","steps":["trace[352564120] 'count revisions from in-memory index tree' (duration: 182.132203ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:05:59.296086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21703} {"level":"info","ts":"2026-09-02T02:05:59.414987Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21703,"took":"115.214167ms","hash":2254922177,"current-db-size-bytes":55824384,"current-db-size":"56 MB","current-db-size-in-use-bytes":42651648,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-02T02:05:59.415212Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2254922177,"revision":21703,"compact-revision":19398} {"level":"info","ts":"2026-09-02T02:09:50.67583Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b1d.wal"} {"level":"info","ts":"2026-09-02T02:10:59.303532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24571} {"level":"info","ts":"2026-09-02T02:10:59.462274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24571,"took":"152.769421ms","hash":3955419802,"current-db-size-bytes":56246272,"current-db-size":"56 MB","current-db-size-in-use-bytes":34455552,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-02T02:10:59.462379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3955419802,"revision":24571,"compact-revision":21703} {"level":"info","ts":"2026-09-02T02:15:25.67394Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"14f8f2dfdaccebdb","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-02T02:15:25.687274Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-02T02:15:25.687386Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-02T02:15:59.315148Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27082} {"level":"info","ts":"2026-09-02T02:15:59.443489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27082,"took":"124.318745ms","hash":4077767944,"current-db-size-bytes":56246272,"current-db-size":"56 MB","current-db-size-in-use-bytes":29753344,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-02T02:15:59.443563Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4077767944,"revision":27082,"compact-revision":24571}