{"level":"warn","ts":"2026-10-06T09:56:53.75284Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-06T09:56:53.752921Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.162: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.162:2380","--initial-cluster=instance=https://162.253.55.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.162: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-10-06T09:56:53.75298Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-06T09:56:53.752989Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.162:2380"]} {"level":"info","ts":"2026-10-06T09:56:53.753003Z","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-10-06T09:56:53.754753Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.162:2379"]} {"level":"info","ts":"2026-10-06T09:56:53.75483Z","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.162:2380"],"listen-peer-urls":["https://162.253.55.162:2380"],"advertise-client-urls":["https://162.253.55.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.162: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-10-06T09:56:53.76027Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.48896ms"} {"level":"info","ts":"2026-10-06T09:56:53.767391Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f61dce011a423b00","cluster-id":"d363ade277557413"} {"level":"info","ts":"2026-10-06T09:56:53.767449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 switched to configuration voters=()"} {"level":"info","ts":"2026-10-06T09:56:53.767465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became follower at term 0"} {"level":"info","ts":"2026-10-06T09:56:53.767473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f61dce011a423b00 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-06T09:56:53.767478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became follower at term 1"} {"level":"info","ts":"2026-10-06T09:56:53.767507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 switched to configuration voters=(17734557411785718528)"} {"level":"warn","ts":"2026-10-06T09:56:53.773981Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-06T09:56:53.776867Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-06T09:56:53.7782Z","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-10-06T09:56:53.780337Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f61dce011a423b00","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-06T09:56:53.780649Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f61dce011a423b00","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-06T09:56:53.780694Z","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-10-06T09:56:53.780804Z","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-10-06T09:56:53.780823Z","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-10-06T09:56:53.782158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 switched to configuration voters=(17734557411785718528)"} {"level":"info","ts":"2026-10-06T09:56:53.782505Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d363ade277557413","local-member-id":"f61dce011a423b00","added-peer-id":"f61dce011a423b00","added-peer-peer-urls":["https://162.253.55.162:2380"]} {"level":"info","ts":"2026-10-06T09:56:53.783905Z","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-10-06T09:56:53.78409Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f61dce011a423b00","initial-advertise-peer-urls":["https://162.253.55.162:2380"],"listen-peer-urls":["https://162.253.55.162:2380"],"advertise-client-urls":["https://162.253.55.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-06T09:56:53.784118Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-06T09:56:53.78415Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.162:2380"} {"level":"info","ts":"2026-10-06T09:56:53.784167Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.162:2380"} {"level":"info","ts":"2026-10-06T09:56:54.668626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 is starting a new election at term 1"} {"level":"info","ts":"2026-10-06T09:56:54.668683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-06T09:56:54.668705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 received MsgPreVoteResp from f61dce011a423b00 at term 1"} {"level":"info","ts":"2026-10-06T09:56:54.668718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became candidate at term 2"} {"level":"info","ts":"2026-10-06T09:56:54.668724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 received MsgVoteResp from f61dce011a423b00 at term 2"} {"level":"info","ts":"2026-10-06T09:56:54.668734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became leader at term 2"} {"level":"info","ts":"2026-10-06T09:56:54.668745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f61dce011a423b00 elected leader f61dce011a423b00 at term 2"} {"level":"info","ts":"2026-10-06T09:56:54.669733Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T09:56:54.670416Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f61dce011a423b00","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.162:2379]}","request-path":"/0/members/f61dce011a423b00/attributes","cluster-id":"d363ade277557413","publish-timeout":"7s"} {"level":"info","ts":"2026-10-06T09:56:54.670546Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T09:56:54.670574Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T09:56:54.671022Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d363ade277557413","local-member-id":"f61dce011a423b00","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T09:56:54.671265Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T09:56:54.671294Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T09:56:54.672424Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-06T09:56:54.672442Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-06T09:56:54.673518Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.162:2379"} {"level":"info","ts":"2026-10-06T09:56:54.673985Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-06T09:56:57.368715Z","caller":"traceutil/trace.go:171","msg":"trace[1129861140] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"207.108325ms","start":"2026-10-06T09:56:57.161579Z","end":"2026-10-06T09:56:57.368687Z","steps":["trace[1129861140] 'read index received' (duration: 206.84028ms)","trace[1129861140] 'applied index is now lower than readState.Index' (duration: 266.635µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T09:56:57.368909Z","caller":"traceutil/trace.go:171","msg":"trace[1854575331] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"210.487918ms","start":"2026-10-06T09:56:57.158388Z","end":"2026-10-06T09:56:57.368875Z","steps":["trace[1854575331] 'process raft request' (duration: 210.13649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:56:57.369185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.568566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-06T09:56:57.369304Z","caller":"traceutil/trace.go:171","msg":"trace[932070400] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:72; }","duration":"207.739999ms","start":"2026-10-06T09:56:57.161536Z","end":"2026-10-06T09:56:57.369276Z","steps":["trace[932070400] 'agreement among raft nodes before linearized reading' (duration: 207.405862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:56:57.369442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.382856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-10-06T09:56:57.369498Z","caller":"traceutil/trace.go:171","msg":"trace[1984954572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"174.50939ms","start":"2026-10-06T09:56:57.194973Z","end":"2026-10-06T09:56:57.369483Z","steps":["trace[1984954572] 'agreement among raft nodes before linearized reading' (duration: 174.372697ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:56:57.483272Z","caller":"traceutil/trace.go:171","msg":"trace[1408556926] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"106.739082ms","start":"2026-10-06T09:56:57.376475Z","end":"2026-10-06T09:56:57.483214Z","steps":["trace[1408556926] 'process raft request' (duration: 99.487626ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:57:38.98713Z","caller":"traceutil/trace.go:171","msg":"trace[1979643446] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"133.897939ms","start":"2026-10-06T09:57:38.853196Z","end":"2026-10-06T09:57:38.987094Z","steps":["trace[1979643446] 'read index received' (duration: 46.83203ms)","trace[1979643446] 'applied index is now lower than readState.Index' (duration: 87.064939ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T09:57:38.987341Z","caller":"traceutil/trace.go:171","msg":"trace[29312608] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"146.757944ms","start":"2026-10-06T09:57:38.840569Z","end":"2026-10-06T09:57:38.987327Z","steps":["trace[29312608] 'process raft request' (duration: 59.518582ms)","trace[29312608] 'compare' (duration: 86.889747ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T09:57:38.987369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.148483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T09:57:38.987417Z","caller":"traceutil/trace.go:171","msg":"trace[1953025613] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:580; }","duration":"134.244164ms","start":"2026-10-06T09:57:38.853155Z","end":"2026-10-06T09:57:38.987399Z","steps":["trace[1953025613] 'agreement among raft nodes before linearized reading' (duration: 134.065172ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:57:39.673148Z","caller":"traceutil/trace.go:171","msg":"trace[438633808] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"413.146475ms","start":"2026-10-06T09:57:39.259979Z","end":"2026-10-06T09:57:39.673126Z","steps":["trace[438633808] 'process raft request' (duration: 412.976652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:57:39.673677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:57:39.259953Z","time spent":"413.251636ms","remote":"127.0.0.1:53948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T09:57:39.697868Z","caller":"traceutil/trace.go:171","msg":"trace[1307189012] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"148.767687ms","start":"2026-10-06T09:57:39.548998Z","end":"2026-10-06T09:57:39.697765Z","steps":["trace[1307189012] 'read index received' (duration: 124.952588ms)","trace[1307189012] 'applied index is now lower than readState.Index' (duration: 23.813339ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T09:57:39.699697Z","caller":"traceutil/trace.go:171","msg":"trace[484357073] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"304.023252ms","start":"2026-10-06T09:57:39.395636Z","end":"2026-10-06T09:57:39.699659Z","steps":["trace[484357073] 'process raft request' (duration: 299.007897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:57:39.699867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:57:39.395606Z","time spent":"304.181514ms","remote":"127.0.0.1:53856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T09:57:39.713187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.179249ms","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-10-06T09:57:39.713272Z","caller":"traceutil/trace.go:171","msg":"trace[1368278090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"164.329872ms","start":"2026-10-06T09:57:39.54892Z","end":"2026-10-06T09:57:39.713249Z","steps":["trace[1368278090] 'agreement among raft nodes before linearized reading' (duration: 150.705017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:57:39.71332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.465452ms","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-10-06T09:57:39.713853Z","caller":"traceutil/trace.go:171","msg":"trace[63752094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"122.823347ms","start":"2026-10-06T09:57:39.590753Z","end":"2026-10-06T09:57:39.713576Z","steps":["trace[63752094] 'agreement among raft nodes before linearized reading' (duration: 108.906157ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:57:41.5308Z","caller":"traceutil/trace.go:171","msg":"trace[1584708523] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"110.61579ms","start":"2026-10-06T09:57:41.420164Z","end":"2026-10-06T09:57:41.53078Z","steps":["trace[1584708523] 'process raft request' (duration: 110.511788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:57:55.177898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.41372ms","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-10-06T09:57:55.17799Z","caller":"traceutil/trace.go:171","msg":"trace[1219834712] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:754; }","duration":"117.547171ms","start":"2026-10-06T09:57:55.060424Z","end":"2026-10-06T09:57:55.177971Z","steps":["trace[1219834712] 'range keys from in-memory index tree' (duration: 117.230527ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:57:55.33191Z","caller":"traceutil/trace.go:171","msg":"trace[1682410173] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"149.316071ms","start":"2026-10-06T09:57:55.182564Z","end":"2026-10-06T09:57:55.33188Z","steps":["trace[1682410173] 'process raft request' (duration: 146.273289ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:17.002395Z","caller":"traceutil/trace.go:171","msg":"trace[223897559] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"231.867482ms","start":"2026-10-06T09:59:16.770505Z","end":"2026-10-06T09:59:17.002372Z","steps":["trace[223897559] 'process raft request' (duration: 231.744061ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:17.236247Z","caller":"traceutil/trace.go:171","msg":"trace[216971135] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"122.438483ms","start":"2026-10-06T09:59:17.113782Z","end":"2026-10-06T09:59:17.236221Z","steps":["trace[216971135] 'process raft request' (duration: 122.291961ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:17.584958Z","caller":"traceutil/trace.go:171","msg":"trace[1153288701] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"180.109003ms","start":"2026-10-06T09:59:17.404815Z","end":"2026-10-06T09:59:17.584924Z","steps":["trace[1153288701] 'process raft request' (duration: 127.636573ms)","trace[1153288701] 'compare' (duration: 52.086506ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T09:59:24.474318Z","caller":"traceutil/trace.go:171","msg":"trace[1970638443] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"100.29879ms","start":"2026-10-06T09:59:24.373991Z","end":"2026-10-06T09:59:24.47429Z","steps":["trace[1970638443] 'process raft request' (duration: 99.875006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:59:50.811266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.641807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T09:59:50.811358Z","caller":"traceutil/trace.go:171","msg":"trace[331964680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2082; }","duration":"115.799819ms","start":"2026-10-06T09:59:50.69554Z","end":"2026-10-06T09:59:50.81134Z","steps":["trace[331964680] 'range keys from in-memory index tree' (duration: 115.527136ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:59:50.811455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.572676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-06T09:59:50.811535Z","caller":"traceutil/trace.go:171","msg":"trace[763141389] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2082; }","duration":"163.707728ms","start":"2026-10-06T09:59:50.647807Z","end":"2026-10-06T09:59:50.811514Z","steps":["trace[763141389] 'range keys from in-memory index tree' (duration: 163.344894ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:51.449239Z","caller":"traceutil/trace.go:171","msg":"trace[1363195313] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"110.996795ms","start":"2026-10-06T09:59:51.338209Z","end":"2026-10-06T09:59:51.449205Z","steps":["trace[1363195313] 'process raft request' (duration: 110.789383ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:51.848459Z","caller":"traceutil/trace.go:171","msg":"trace[1758516727] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"136.544457ms","start":"2026-10-06T09:59:51.71186Z","end":"2026-10-06T09:59:51.848404Z","steps":["trace[1758516727] 'process raft request' (duration: 114.273963ms)","trace[1758516727] 'compare' (duration: 21.82149ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:05:44.637933Z","caller":"traceutil/trace.go:171","msg":"trace[355542847] transaction","detail":"{read_only:false; response_revision:4620; number_of_response:1; }","duration":"121.281779ms","start":"2026-10-06T10:05:44.516625Z","end":"2026-10-06T10:05:44.637906Z","steps":["trace[355542847] 'process raft request' (duration: 121.042216ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:57.386695Z","caller":"traceutil/trace.go:171","msg":"trace[1113221510] linearizableReadLoop","detail":"{readStateIndex:5083; appliedIndex:5081; }","duration":"143.285037ms","start":"2026-10-06T10:05:57.243391Z","end":"2026-10-06T10:05:57.386676Z","steps":["trace[1113221510] 'read index received' (duration: 142.870242ms)","trace[1113221510] 'applied index is now lower than readState.Index' (duration: 413.985µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:05:57.387018Z","caller":"traceutil/trace.go:171","msg":"trace[155600824] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"176.756599ms","start":"2026-10-06T10:05:57.210248Z","end":"2026-10-06T10:05:57.387005Z","steps":["trace[155600824] 'process raft request' (duration: 176.061851ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:57.387389Z","caller":"traceutil/trace.go:171","msg":"trace[149545404] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"162.040952ms","start":"2026-10-06T10:05:57.225306Z","end":"2026-10-06T10:05:57.387347Z","steps":["trace[149545404] 'process raft request' (duration: 161.320164ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:57.387693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.281758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61072"} {"level":"info","ts":"2026-10-06T10:05:57.387753Z","caller":"traceutil/trace.go:171","msg":"trace[54207692] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4888; }","duration":"144.378389ms","start":"2026-10-06T10:05:57.24336Z","end":"2026-10-06T10:05:57.387739Z","steps":["trace[54207692] 'agreement among raft nodes before linearized reading' (duration: 144.164906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:57.388547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.626559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-10-06T10:05:57.388686Z","caller":"traceutil/trace.go:171","msg":"trace[298599824] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4888; }","duration":"114.59854ms","start":"2026-10-06T10:05:57.274069Z","end":"2026-10-06T10:05:57.388668Z","steps":["trace[298599824] 'agreement among raft nodes before linearized reading' (duration: 113.489027ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:57.562403Z","caller":"traceutil/trace.go:171","msg":"trace[982962203] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"168.215686ms","start":"2026-10-06T10:05:57.394167Z","end":"2026-10-06T10:05:57.562383Z","steps":["trace[982962203] 'process raft request' (duration: 159.107296ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:57.563257Z","caller":"traceutil/trace.go:171","msg":"trace[1196191258] linearizableReadLoop","detail":"{readStateIndex:5085; appliedIndex:5083; }","duration":"166.342805ms","start":"2026-10-06T10:05:57.396891Z","end":"2026-10-06T10:05:57.563233Z","steps":["trace[1196191258] 'read index received' (duration: 156.371113ms)","trace[1196191258] 'applied index is now lower than readState.Index' (duration: 9.970582ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:05:57.563362Z","caller":"traceutil/trace.go:171","msg":"trace[557551507] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"165.676295ms","start":"2026-10-06T10:05:57.397674Z","end":"2026-10-06T10:05:57.563351Z","steps":["trace[557551507] 'process raft request' (duration: 165.512284ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:57.56338Z","caller":"traceutil/trace.go:171","msg":"trace[319059474] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"167.534948ms","start":"2026-10-06T10:05:57.395834Z","end":"2026-10-06T10:05:57.563369Z","steps":["trace[319059474] 'process raft request' (duration: 167.222845ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:57.563768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.579754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1455"} {"level":"warn","ts":"2026-10-06T10:05:57.563812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.90544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9425"} {"level":"warn","ts":"2026-10-06T10:05:57.563813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.050682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-10-06T10:05:57.563862Z","caller":"traceutil/trace.go:171","msg":"trace[999403623] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4891; }","duration":"166.995901ms","start":"2026-10-06T10:05:57.396856Z","end":"2026-10-06T10:05:57.563851Z","steps":["trace[999403623] 'agreement among raft nodes before linearized reading' (duration: 166.840789ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:57.563871Z","caller":"traceutil/trace.go:171","msg":"trace[986560743] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4891; }","duration":"160.732785ms","start":"2026-10-06T10:05:57.403114Z","end":"2026-10-06T10:05:57.563847Z","steps":["trace[986560743] 'agreement among raft nodes before linearized reading' (duration: 160.432821ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:57.563889Z","caller":"traceutil/trace.go:171","msg":"trace[1396045675] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4891; }","duration":"133.181664ms","start":"2026-10-06T10:05:57.430688Z","end":"2026-10-06T10:05:57.563869Z","steps":["trace[1396045675] 'agreement among raft nodes before linearized reading' (duration: 132.916431ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:57.564217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.969899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4849"} {"level":"info","ts":"2026-10-06T10:05:57.564266Z","caller":"traceutil/trace.go:171","msg":"trace[1315181457] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4891; }","duration":"146.051049ms","start":"2026-10-06T10:05:57.418201Z","end":"2026-10-06T10:05:57.564252Z","steps":["trace[1315181457] 'agreement among raft nodes before linearized reading' (duration: 145.894517ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:57.564345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.143618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9573"} {"level":"info","ts":"2026-10-06T10:05:57.564412Z","caller":"traceutil/trace.go:171","msg":"trace[1290145883] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4891; }","duration":"155.237379ms","start":"2026-10-06T10:05:57.409164Z","end":"2026-10-06T10:05:57.564401Z","steps":["trace[1290145883] 'agreement among raft nodes before linearized reading' (duration: 155.118618ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:57.899611Z","caller":"traceutil/trace.go:171","msg":"trace[897983044] linearizableReadLoop","detail":"{readStateIndex:5095; appliedIndex:5094; }","duration":"171.612687ms","start":"2026-10-06T10:05:57.727972Z","end":"2026-10-06T10:05:57.899585Z","steps":["trace[897983044] 'read index received' (duration: 145.972189ms)","trace[897983044] 'applied index is now lower than readState.Index' (duration: 25.639658ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:05:57.899625Z","caller":"traceutil/trace.go:171","msg":"trace[727029131] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"173.888735ms","start":"2026-10-06T10:05:57.725701Z","end":"2026-10-06T10:05:57.89959Z","steps":["trace[727029131] 'process raft request' (duration: 148.334447ms)","trace[727029131] 'compare' (duration: 25.361126ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:05:57.89978Z","caller":"traceutil/trace.go:171","msg":"trace[1752792480] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"171.145932ms","start":"2026-10-06T10:05:57.728611Z","end":"2026-10-06T10:05:57.899757Z","steps":["trace[1752792480] 'process raft request' (duration: 170.94761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:57.900181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.186184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-10-06T10:05:57.900232Z","caller":"traceutil/trace.go:171","msg":"trace[1316338815] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4901; }","duration":"172.291925ms","start":"2026-10-06T10:05:57.727928Z","end":"2026-10-06T10:05:57.90022Z","steps":["trace[1316338815] 'agreement among raft nodes before linearized reading' (duration: 171.771408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:57.911632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.092653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"warn","ts":"2026-10-06T10:05:57.911652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.620521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-06T10:05:57.91169Z","caller":"traceutil/trace.go:171","msg":"trace[1636619804] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4902; }","duration":"177.183245ms","start":"2026-10-06T10:05:57.734494Z","end":"2026-10-06T10:05:57.911677Z","steps":["trace[1636619804] 'agreement among raft nodes before linearized reading' (duration: 176.973112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:57.911743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.694585ms","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-10-06T10:05:57.91179Z","caller":"traceutil/trace.go:171","msg":"trace[1107765816] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4902; }","duration":"149.770043ms","start":"2026-10-06T10:05:57.761984Z","end":"2026-10-06T10:05:57.911754Z","steps":["trace[1107765816] 'agreement among raft nodes before linearized reading' (duration: 149.462919ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:57.911829Z","caller":"traceutil/trace.go:171","msg":"trace[120698957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4902; }","duration":"150.846606ms","start":"2026-10-06T10:05:57.760961Z","end":"2026-10-06T10:05:57.911807Z","steps":["trace[120698957] 'agreement among raft nodes before linearized reading' (duration: 150.595683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:57.912004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.944123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-10-06T10:05:57.91206Z","caller":"traceutil/trace.go:171","msg":"trace[1439896409] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4902; }","duration":"178.012723ms","start":"2026-10-06T10:05:57.734033Z","end":"2026-10-06T10:05:57.912046Z","steps":["trace[1439896409] 'agreement among raft nodes before linearized reading' (duration: 177.837621ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:58.079447Z","caller":"traceutil/trace.go:171","msg":"trace[1592434545] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"111.997298ms","start":"2026-10-06T10:05:57.96742Z","end":"2026-10-06T10:05:58.079418Z","steps":["trace[1592434545] 'process raft request' (duration: 50.254185ms)","trace[1592434545] 'compare' (duration: 61.535031ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:06:10.283504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.818763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:06:10.283608Z","caller":"traceutil/trace.go:171","msg":"trace[616400204] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:5077; }","duration":"164.980855ms","start":"2026-10-06T10:06:10.118603Z","end":"2026-10-06T10:06:10.283584Z","steps":["trace[616400204] 'count revisions from in-memory index tree' (duration: 164.711721ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:06:50.342354Z","caller":"traceutil/trace.go:171","msg":"trace[966555213] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"100.284991ms","start":"2026-10-06T10:06:50.242045Z","end":"2026-10-06T10:06:50.34233Z","steps":["trace[966555213] 'process raft request' (duration: 100.183629ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:06:54.701441Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2856} {"level":"info","ts":"2026-10-06T10:06:54.779037Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2856,"took":"76.094718ms","hash":3234907998,"current-db-size-bytes":52006912,"current-db-size":"52 MB","current-db-size-in-use-bytes":28413952,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-06T10:06:54.779821Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3234907998,"revision":2856,"compact-revision":-1} {"level":"info","ts":"2026-10-06T10:11:54.709091Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5451} {"level":"info","ts":"2026-10-06T10:11:54.8001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5451,"took":"89.059743ms","hash":566864235,"current-db-size-bytes":52006912,"current-db-size":"52 MB","current-db-size-in-use-bytes":18481152,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-06T10:11:54.800185Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":566864235,"revision":5451,"compact-revision":2856}