{"level":"warn","ts":"2026-09-08T22:48:39.789326Z","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-08T22:48:39.789436Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212: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.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212: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-08T22:48:39.789593Z","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-08T22:48:39.789611Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-09-08T22:48:39.78967Z","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-08T22:48:39.790776Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-09-08T22:48:39.790869Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212: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-08T22:48:39.795768Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.369679ms"} {"level":"info","ts":"2026-09-08T22:48:39.801778Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-09-08T22:48:39.801833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T22:48:39.801921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-09-08T22:48:39.801972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T22:48:39.801985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-09-08T22:48:39.802076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-09-08T22:48:39.808999Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T22:48:39.810585Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T22:48:39.811948Z","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-08T22:48:39.813814Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T22:48:39.817475Z","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-08T22:48:39.817798Z","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-08T22:48:39.817813Z","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-08T22:48:39.817977Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T22:48:39.819963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-09-08T22:48:39.820188Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-09-08T22:48:39.820695Z","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-08T22:48:39.820775Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-09-08T22:48:39.82085Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-09-08T22:48:39.823462Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T22:48:39.82353Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T22:48:40.802376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T22:48:40.802462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T22:48:40.802504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-09-08T22:48:40.802523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-09-08T22:48:40.802534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-09-08T22:48:40.80255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-09-08T22:48:40.802565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-09-08T22:48:40.803627Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:48:40.804574Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T22:48:40.804591Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T22:48:40.804651Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T22:48:40.804823Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T22:48:40.804848Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T22:48:40.805411Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:48:40.805577Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:48:40.805618Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:48:40.807309Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T22:48:40.808223Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"warn","ts":"2026-09-08T22:48:59.560155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.05699ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-09-08T22:48:59.823132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.590394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-08T22:48:59.823269Z","caller":"traceutil/trace.go:171","msg":"trace[943563538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"156.80037ms","start":"2026-09-08T22:48:59.666444Z","end":"2026-09-08T22:48:59.823244Z","steps":["trace[943563538] 'range keys from in-memory index tree' (duration: 156.486471ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:48:59.996785Z","caller":"traceutil/trace.go:171","msg":"trace[645774384] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"167.604571ms","start":"2026-09-08T22:48:59.82915Z","end":"2026-09-08T22:48:59.996754Z","steps":["trace[645774384] 'process raft request' (duration: 167.348933ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:49:20.47498Z","caller":"traceutil/trace.go:171","msg":"trace[1975341186] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"183.529968ms","start":"2026-09-08T22:49:20.291399Z","end":"2026-09-08T22:49:20.474928Z","steps":["trace[1975341186] 'read index received' (duration: 54.865349ms)","trace[1975341186] 'applied index is now lower than readState.Index' (duration: 128.661969ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:49:20.475057Z","caller":"traceutil/trace.go:171","msg":"trace[1442656786] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"190.10274ms","start":"2026-09-08T22:49:20.28492Z","end":"2026-09-08T22:49:20.475022Z","steps":["trace[1442656786] 'process raft request' (duration: 178.81451ms)","trace[1442656786] 'compare' (duration: 10.917191ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:49:20.475458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.02003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-09-08T22:49:20.475558Z","caller":"traceutil/trace.go:171","msg":"trace[286700766] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:608; }","duration":"184.161993ms","start":"2026-09-08T22:49:20.291372Z","end":"2026-09-08T22:49:20.475534Z","steps":["trace[286700766] 'agreement among raft nodes before linearized reading' (duration: 183.751003ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:49:24.838092Z","caller":"traceutil/trace.go:171","msg":"trace[1288971532] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"134.408957ms","start":"2026-09-08T22:49:24.703641Z","end":"2026-09-08T22:49:24.83805Z","steps":["trace[1288971532] 'process raft request' (duration: 133.971676ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:49:24.8383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.639359ms","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-08T22:49:24.838446Z","caller":"traceutil/trace.go:171","msg":"trace[1736311447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"118.863574ms","start":"2026-09-08T22:49:24.719566Z","end":"2026-09-08T22:49:24.83843Z","steps":["trace[1736311447] 'agreement among raft nodes before linearized reading' (duration: 118.524756ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:49:24.837961Z","caller":"traceutil/trace.go:171","msg":"trace[1844433061] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"118.28192ms","start":"2026-09-08T22:49:24.719643Z","end":"2026-09-08T22:49:24.837925Z","steps":["trace[1844433061] 'read index received' (duration: 117.990433ms)","trace[1844433061] 'applied index is now lower than readState.Index' (duration: 290.457µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:49:24.996658Z","caller":"traceutil/trace.go:171","msg":"trace[307490416] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"242.463451ms","start":"2026-09-08T22:49:24.754153Z","end":"2026-09-08T22:49:24.996617Z","steps":["trace[307490416] 'process raft request' (duration: 238.845914ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:49:25.001523Z","caller":"traceutil/trace.go:171","msg":"trace[525095624] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"158.248059ms","start":"2026-09-08T22:49:24.843241Z","end":"2026-09-08T22:49:25.001489Z","steps":["trace[525095624] 'process raft request' (duration: 158.079115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:49:25.569438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.702095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:49:25.569551Z","caller":"traceutil/trace.go:171","msg":"trace[55181060] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"427.516044ms","start":"2026-09-08T22:49:25.142015Z","end":"2026-09-08T22:49:25.569531Z","steps":["trace[55181060] 'read index received' (duration: 262.163995ms)","trace[55181060] 'applied index is now lower than readState.Index' (duration: 165.350309ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:49:25.569659Z","caller":"traceutil/trace.go:171","msg":"trace[189026790] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"499.991001ms","start":"2026-09-08T22:49:25.069553Z","end":"2026-09-08T22:49:25.569544Z","steps":["trace[189026790] 'process raft request' (duration: 334.720964ms)","trace[189026790] 'compare' (duration: 164.439657ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:49:25.569804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.786711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z9jnh\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-09-08T22:49:25.569805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.696563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:49:25.569834Z","caller":"traceutil/trace.go:171","msg":"trace[2046831318] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z9jnh; range_end:; response_count:1; response_revision:638; }","duration":"427.867113ms","start":"2026-09-08T22:49:25.141956Z","end":"2026-09-08T22:49:25.569823Z","steps":["trace[2046831318] 'agreement among raft nodes before linearized reading' (duration: 427.673409ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:49:25.569877Z","caller":"traceutil/trace.go:171","msg":"trace[700208794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"164.832706ms","start":"2026-09-08T22:49:25.405025Z","end":"2026-09-08T22:49:25.569857Z","steps":["trace[700208794] 'agreement among raft nodes before linearized reading' (duration: 164.629451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:49:25.569876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:49:25.141936Z","time spent":"427.932654ms","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-z9jnh\" "} {"level":"warn","ts":"2026-09-08T22:49:25.569976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:49:25.069515Z","time spent":"500.272957ms","remote":"127.0.0.1:33384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T22:50:12.747727Z","caller":"traceutil/trace.go:171","msg":"trace[477816448] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"341.655386ms","start":"2026-09-08T22:50:12.406051Z","end":"2026-09-08T22:50:12.747707Z","steps":["trace[477816448] 'read index received' (duration: 339.003239ms)","trace[477816448] 'applied index is now lower than readState.Index' (duration: 2.651177ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:50:12.747792Z","caller":"traceutil/trace.go:171","msg":"trace[1548916319] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"355.473711ms","start":"2026-09-08T22:50:12.392261Z","end":"2026-09-08T22:50:12.747735Z","steps":["trace[1548916319] 'process raft request' (duration: 352.878785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:50:12.747878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.81402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:50:12.748079Z","caller":"traceutil/trace.go:171","msg":"trace[1031751367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"342.048113ms","start":"2026-09-08T22:50:12.406017Z","end":"2026-09-08T22:50:12.748065Z","steps":["trace[1031751367] 'agreement among raft nodes before linearized reading' (duration: 341.811529ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:50:12.748159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:50:12.406004Z","time spent":"342.143975ms","remote":"127.0.0.1:33010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-08T22:50:12.748528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:50:12.392233Z","time spent":"355.730675ms","remote":"127.0.0.1:33276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T22:51:03.063274Z","caller":"traceutil/trace.go:171","msg":"trace[1407532422] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"138.460902ms","start":"2026-09-08T22:51:02.924799Z","end":"2026-09-08T22:51:03.063259Z","steps":["trace[1407532422] 'process raft request' (duration: 138.232469ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:03.063273Z","caller":"traceutil/trace.go:171","msg":"trace[893974486] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"140.060796ms","start":"2026-09-08T22:51:02.923187Z","end":"2026-09-08T22:51:03.063248Z","steps":["trace[893974486] 'process raft request' (duration: 98.315694ms)","trace[893974486] 'compare' (duration: 41.396637ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:03.063375Z","caller":"traceutil/trace.go:171","msg":"trace[556801587] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1908; }","duration":"139.213873ms","start":"2026-09-08T22:51:02.924127Z","end":"2026-09-08T22:51:03.063341Z","steps":["trace[556801587] 'read index received' (duration: 63.894519ms)","trace[556801587] 'applied index is now lower than readState.Index' (duration: 75.317994ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:51:03.063809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.257183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3963"} {"level":"warn","ts":"2026-09-08T22:51:03.063822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.68193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-08T22:51:03.063855Z","caller":"traceutil/trace.go:171","msg":"trace[93508061] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1849; }","duration":"107.342905ms","start":"2026-09-08T22:51:02.956498Z","end":"2026-09-08T22:51:03.063841Z","steps":["trace[93508061] 'agreement among raft nodes before linearized reading' (duration: 107.193632ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:03.063866Z","caller":"traceutil/trace.go:171","msg":"trace[176148870] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:1; response_revision:1849; }","duration":"139.767751ms","start":"2026-09-08T22:51:02.924086Z","end":"2026-09-08T22:51:03.063854Z","steps":["trace[176148870] 'agreement among raft nodes before linearized reading' (duration: 139.502227ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:03.063955Z","caller":"traceutil/trace.go:171","msg":"trace[323341146] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"138.293149ms","start":"2026-09-08T22:51:02.924916Z","end":"2026-09-08T22:51:03.063209Z","steps":["trace[323341146] 'process raft request' (duration: 138.160848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:05.401705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.638941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:51:05.401815Z","caller":"traceutil/trace.go:171","msg":"trace[1235967892] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1976; }","duration":"214.839112ms","start":"2026-09-08T22:51:05.186961Z","end":"2026-09-08T22:51:05.4018Z","steps":["trace[1235967892] 'read index received' (duration: 5.968647ms)","trace[1235967892] 'applied index is now lower than readState.Index' (duration: 208.869355ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:51:05.402178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.208857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0c93c6d9-af00-422b-af33-47b4a956e175\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-08T22:51:05.402221Z","caller":"traceutil/trace.go:171","msg":"trace[1743686275] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0c93c6d9-af00-422b-af33-47b4a956e175; range_end:; response_count:1; response_revision:1911; }","duration":"215.288318ms","start":"2026-09-08T22:51:05.18692Z","end":"2026-09-08T22:51:05.402209Z","steps":["trace[1743686275] 'agreement among raft nodes before linearized reading' (duration: 214.920082ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:05.402512Z","caller":"traceutil/trace.go:171","msg":"trace[832041821] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"274.767495ms","start":"2026-09-08T22:51:05.127727Z","end":"2026-09-08T22:51:05.402494Z","steps":["trace[832041821] 'process raft request' (duration: 65.259841ms)","trace[832041821] 'compare' (duration: 208.366877ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:05.847474Z","caller":"traceutil/trace.go:171","msg":"trace[426292948] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1978; }","duration":"227.997454ms","start":"2026-09-08T22:51:05.619451Z","end":"2026-09-08T22:51:05.847449Z","steps":["trace[426292948] 'read index received' (duration: 214.03313ms)","trace[426292948] 'applied index is now lower than readState.Index' (duration: 13.962984ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:05.847496Z","caller":"traceutil/trace.go:171","msg":"trace[1574882545] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"264.77892ms","start":"2026-09-08T22:51:05.582659Z","end":"2026-09-08T22:51:05.847438Z","steps":["trace[1574882545] 'process raft request' (duration: 250.877707ms)","trace[1574882545] 'compare' (duration: 13.671579ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:51:05.847708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.237438ms","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":"warn","ts":"2026-09-08T22:51:05.848471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.698061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0c93c6d9-af00-422b-af33-47b4a956e175\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-08T22:51:05.8476Z","caller":"traceutil/trace.go:171","msg":"trace[855381651] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"108.493831ms","start":"2026-09-08T22:51:05.739097Z","end":"2026-09-08T22:51:05.847591Z","steps":["trace[855381651] 'process raft request' (duration: 108.311568ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:05.848736Z","caller":"traceutil/trace.go:171","msg":"trace[957591820] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0c93c6d9-af00-422b-af33-47b4a956e175; range_end:; response_count:1; response_revision:1914; }","duration":"140.793903ms","start":"2026-09-08T22:51:05.70772Z","end":"2026-09-08T22:51:05.848514Z","steps":["trace[957591820] 'agreement among raft nodes before linearized reading' (duration: 140.469418ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:05.848361Z","caller":"traceutil/trace.go:171","msg":"trace[934198426] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1914; }","duration":"228.950158ms","start":"2026-09-08T22:51:05.619385Z","end":"2026-09-08T22:51:05.848335Z","steps":["trace[934198426] 'agreement among raft nodes before linearized reading' (duration: 228.185487ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:17.743448Z","caller":"traceutil/trace.go:171","msg":"trace[717940195] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"128.870926ms","start":"2026-09-08T22:51:17.614554Z","end":"2026-09-08T22:51:17.743425Z","steps":["trace[717940195] 'process raft request' (duration: 128.730793ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:44.455676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.30984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T22:51:44.455787Z","caller":"traceutil/trace.go:171","msg":"trace[564499092] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2173; }","duration":"287.466672ms","start":"2026-09-08T22:51:44.168296Z","end":"2026-09-08T22:51:44.455763Z","steps":["trace[564499092] 'count revisions from in-memory index tree' (duration: 287.159087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:44.456343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.532666ms","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-08T22:51:44.456447Z","caller":"traceutil/trace.go:171","msg":"trace[1944232877] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2173; }","duration":"112.699629ms","start":"2026-09-08T22:51:44.343728Z","end":"2026-09-08T22:51:44.456427Z","steps":["trace[1944232877] 'range keys from in-memory index tree' (duration: 112.333833ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:44.457676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.65103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-08T22:51:44.457736Z","caller":"traceutil/trace.go:171","msg":"trace[736795426] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:2173; }","duration":"225.735841ms","start":"2026-09-08T22:51:44.231988Z","end":"2026-09-08T22:51:44.457724Z","steps":["trace[736795426] 'range keys from in-memory index tree' (duration: 224.213481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:44.7655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.683688ms","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-08T22:51:44.7656Z","caller":"traceutil/trace.go:171","msg":"trace[1818340780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2175; }","duration":"141.82593ms","start":"2026-09-08T22:51:44.62375Z","end":"2026-09-08T22:51:44.765576Z","steps":["trace[1818340780] 'range keys from in-memory index tree' (duration: 141.523705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:45.042103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.398079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:51:45.042196Z","caller":"traceutil/trace.go:171","msg":"trace[183538145] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"272.326288ms","start":"2026-09-08T22:51:44.769851Z","end":"2026-09-08T22:51:45.042178Z","steps":["trace[183538145] 'process raft request' (duration: 114.765256ms)","trace[183538145] 'compare' (duration: 157.059256ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:52:36.309789Z","caller":"traceutil/trace.go:171","msg":"trace[1720318182] transaction","detail":"{read_only:false; response_revision:2560; number_of_response:1; }","duration":"156.409558ms","start":"2026-09-08T22:52:36.153352Z","end":"2026-09-08T22:52:36.309761Z","steps":["trace[1720318182] 'process raft request' (duration: 156.285294ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:36.989916Z","caller":"traceutil/trace.go:171","msg":"trace[1745652044] linearizableReadLoop","detail":"{readStateIndex:5005; appliedIndex:5004; }","duration":"146.683579ms","start":"2026-09-08T22:57:36.843211Z","end":"2026-09-08T22:57:36.989894Z","steps":["trace[1745652044] 'read index received' (duration: 145.993318ms)","trace[1745652044] 'applied index is now lower than readState.Index' (duration: 689.451µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:57:36.990034Z","caller":"traceutil/trace.go:171","msg":"trace[2125323115] transaction","detail":"{read_only:false; response_revision:4829; number_of_response:1; }","duration":"154.709036ms","start":"2026-09-08T22:57:36.835315Z","end":"2026-09-08T22:57:36.990024Z","steps":["trace[2125323115] 'process raft request' (duration: 153.960744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:57:36.990335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.103686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11455"} {"level":"info","ts":"2026-09-08T22:57:36.990378Z","caller":"traceutil/trace.go:171","msg":"trace[1675519976] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4829; }","duration":"147.178657ms","start":"2026-09-08T22:57:36.843185Z","end":"2026-09-08T22:57:36.990364Z","steps":["trace[1675519976] 'agreement among raft nodes before linearized reading' (duration: 147.011814ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:42.082776Z","caller":"traceutil/trace.go:171","msg":"trace[505723788] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"246.820899ms","start":"2026-09-08T22:57:41.835924Z","end":"2026-09-08T22:57:42.082745Z","steps":["trace[505723788] 'process raft request' (duration: 166.062589ms)","trace[505723788] 'compare' (duration: 80.354263ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:57:42.083244Z","caller":"traceutil/trace.go:171","msg":"trace[618517767] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"225.29807ms","start":"2026-09-08T22:57:41.857932Z","end":"2026-09-08T22:57:42.08323Z","steps":["trace[618517767] 'process raft request' (duration: 224.71977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:57:42.083526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.904569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e3d2a33-a863-41f9-be34-ec5312810078\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-08T22:57:42.083586Z","caller":"traceutil/trace.go:171","msg":"trace[1922481116] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5e3d2a33-a863-41f9-be34-ec5312810078; range_end:; response_count:1; response_revision:4938; }","duration":"213.01983ms","start":"2026-09-08T22:57:41.870554Z","end":"2026-09-08T22:57:42.083574Z","steps":["trace[1922481116] 'agreement among raft nodes before linearized reading' (duration: 212.808207ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:42.083316Z","caller":"traceutil/trace.go:171","msg":"trace[451807704] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5113; }","duration":"212.547762ms","start":"2026-09-08T22:57:41.870607Z","end":"2026-09-08T22:57:42.083155Z","steps":["trace[451807704] 'read index received' (duration: 131.388156ms)","trace[451807704] 'applied index is now lower than readState.Index' (duration: 81.158406ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:57:42.112342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.089443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-08T22:57:42.112357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.315058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T22:57:42.11242Z","caller":"traceutil/trace.go:171","msg":"trace[2118834733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4939; }","duration":"161.264816ms","start":"2026-09-08T22:57:41.951118Z","end":"2026-09-08T22:57:42.112382Z","steps":["trace[2118834733] 'agreement among raft nodes before linearized reading' (duration: 161.101153ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:42.112424Z","caller":"traceutil/trace.go:171","msg":"trace[1730581297] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4939; }","duration":"140.424709ms","start":"2026-09-08T22:57:41.971987Z","end":"2026-09-08T22:57:42.112412Z","steps":["trace[1730581297] 'agreement among raft nodes before linearized reading' (duration: 140.275056ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:45.977672Z","caller":"traceutil/trace.go:171","msg":"trace[1585498376] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"136.1615ms","start":"2026-09-08T22:57:45.841485Z","end":"2026-09-08T22:57:45.977647Z","steps":["trace[1585498376] 'process raft request' (duration: 135.746312ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:45.978667Z","caller":"traceutil/trace.go:171","msg":"trace[344837610] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"115.05939ms","start":"2026-09-08T22:57:45.863582Z","end":"2026-09-08T22:57:45.978641Z","steps":["trace[344837610] 'process raft request' (duration: 114.903007ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:55.571913Z","caller":"traceutil/trace.go:171","msg":"trace[452286674] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"152.763051ms","start":"2026-09-08T22:57:55.419128Z","end":"2026-09-08T22:57:55.571891Z","steps":["trace[452286674] 'process raft request' (duration: 152.620308ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:55.843949Z","caller":"traceutil/trace.go:171","msg":"trace[1570740951] linearizableReadLoop","detail":"{readStateIndex:5305; appliedIndex:5303; }","duration":"393.856173ms","start":"2026-09-08T22:57:55.450068Z","end":"2026-09-08T22:57:55.843924Z","steps":["trace[1570740951] 'read index received' (duration: 121.704906ms)","trace[1570740951] 'applied index is now lower than readState.Index' (duration: 272.149817ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:57:55.844074Z","caller":"traceutil/trace.go:171","msg":"trace[1827633172] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"407.934171ms","start":"2026-09-08T22:57:55.436127Z","end":"2026-09-08T22:57:55.844061Z","steps":["trace[1827633172] 'process raft request' (duration: 322.214793ms)","trace[1827633172] 'compare' (duration: 85.207169ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:57:55.84422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:57:55.436104Z","time spent":"407.999333ms","remote":"127.0.0.1:33276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:57:55.844761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.669227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-08T22:57:55.844838Z","caller":"traceutil/trace.go:171","msg":"trace[1528708971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5121; }","duration":"394.787828ms","start":"2026-09-08T22:57:55.450034Z","end":"2026-09-08T22:57:55.844822Z","steps":["trace[1528708971] 'agreement among raft nodes before linearized reading' (duration: 394.606256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:57:55.844875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:57:55.450021Z","time spent":"394.844479ms","remote":"127.0.0.1:33276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-08T22:57:55.844975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.358459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-09-08T22:57:55.84512Z","caller":"traceutil/trace.go:171","msg":"trace[1825703740] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5121; }","duration":"282.633264ms","start":"2026-09-08T22:57:55.562459Z","end":"2026-09-08T22:57:55.845092Z","steps":["trace[1825703740] 'agreement among raft nodes before linearized reading' (duration: 282.228527ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:58:28.455921Z","caller":"traceutil/trace.go:171","msg":"trace[1907076136] transaction","detail":"{read_only:false; response_revision:5424; number_of_response:1; }","duration":"111.62063ms","start":"2026-09-08T22:58:28.344253Z","end":"2026-09-08T22:58:28.455874Z","steps":["trace[1907076136] 'process raft request' (duration: 47.014455ms)","trace[1907076136] 'compare' (duration: 64.31839ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:58:40.834989Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2910} {"level":"info","ts":"2026-09-08T22:58:40.9382Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2910,"took":"101.698725ms","hash":2386215805,"current-db-size-bytes":53837824,"current-db-size":"54 MB","current-db-size-in-use-bytes":28770304,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-08T22:58:40.938273Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2386215805,"revision":2910,"compact-revision":-1} {"level":"info","ts":"2026-09-08T23:03:40.841782Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5516} {"level":"info","ts":"2026-09-08T23:03:40.961658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5516,"took":"118.266055ms","hash":1371965819,"current-db-size-bytes":53837824,"current-db-size":"54 MB","current-db-size-in-use-bytes":18477056,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-08T23:03:40.96171Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1371965819,"revision":5516,"compact-revision":2910}