{"level":"warn","ts":"2026-09-15T11:21:23.243892Z","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-15T11:21:23.244014Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.32: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.32:2380","--initial-cluster=instance=https://162.253.55.32:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.32:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.32: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-15T11:21:23.244082Z","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-15T11:21:23.244091Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.32:2380"]} {"level":"info","ts":"2026-09-15T11:21:23.244114Z","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-15T11:21:23.24477Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"]} {"level":"info","ts":"2026-09-15T11:21:23.244843Z","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.32:2380"],"listen-peer-urls":["https://162.253.55.32:2380"],"advertise-client-urls":["https://162.253.55.32:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.32: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-15T11:21:23.25258Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.267665ms"} {"level":"info","ts":"2026-09-15T11:21:23.26263Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c665390cd9eacc14","cluster-id":"d19b610fbe64baae"} {"level":"info","ts":"2026-09-15T11:21:23.262883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=()"} {"level":"info","ts":"2026-09-15T11:21:23.262993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became follower at term 0"} {"level":"info","ts":"2026-09-15T11:21:23.263013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c665390cd9eacc14 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-15T11:21:23.263023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became follower at term 1"} {"level":"info","ts":"2026-09-15T11:21:23.26317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=(14295895319515941908)"} {"level":"warn","ts":"2026-09-15T11:21:23.275199Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-15T11:21:23.276898Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-15T11:21:23.278344Z","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-15T11:21:23.280193Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c665390cd9eacc14","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-15T11:21:23.283955Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c665390cd9eacc14","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-15T11:21:23.286157Z","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-15T11:21:23.286206Z","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-15T11:21:23.28622Z","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-15T11:21:23.286568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 switched to configuration voters=(14295895319515941908)"} {"level":"info","ts":"2026-09-15T11:21:23.286783Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d19b610fbe64baae","local-member-id":"c665390cd9eacc14","added-peer-id":"c665390cd9eacc14","added-peer-peer-urls":["https://162.253.55.32:2380"]} {"level":"info","ts":"2026-09-15T11:21:23.28696Z","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-15T11:21:23.287088Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.32:2380"} {"level":"info","ts":"2026-09-15T11:21:23.28728Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.32:2380"} {"level":"info","ts":"2026-09-15T11:21:23.287666Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c665390cd9eacc14","initial-advertise-peer-urls":["https://162.253.55.32:2380"],"listen-peer-urls":["https://162.253.55.32:2380"],"advertise-client-urls":["https://162.253.55.32:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.32:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-15T11:21:23.287767Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-15T11:21:23.365688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 is starting a new election at term 1"} {"level":"info","ts":"2026-09-15T11:21:23.365742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-15T11:21:23.365765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 received MsgPreVoteResp from c665390cd9eacc14 at term 1"} {"level":"info","ts":"2026-09-15T11:21:23.365776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became candidate at term 2"} {"level":"info","ts":"2026-09-15T11:21:23.365782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 received MsgVoteResp from c665390cd9eacc14 at term 2"} {"level":"info","ts":"2026-09-15T11:21:23.36579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c665390cd9eacc14 became leader at term 2"} {"level":"info","ts":"2026-09-15T11:21:23.365797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c665390cd9eacc14 elected leader c665390cd9eacc14 at term 2"} {"level":"info","ts":"2026-09-15T11:21:23.366648Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c665390cd9eacc14","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.32:2379]}","request-path":"/0/members/c665390cd9eacc14/attributes","cluster-id":"d19b610fbe64baae","publish-timeout":"7s"} {"level":"info","ts":"2026-09-15T11:21:23.366757Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T11:21:23.366855Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T11:21:23.367019Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T11:21:23.367255Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-15T11:21:23.367339Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-15T11:21:23.368237Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d19b610fbe64baae","local-member-id":"c665390cd9eacc14","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T11:21:23.368346Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T11:21:23.368384Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T11:21:23.369117Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-15T11:21:23.369251Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.32:2379"} {"level":"info","ts":"2026-09-15T11:21:27.721928Z","caller":"traceutil/trace.go:171","msg":"trace[334321934] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"305.05292ms","start":"2026-09-15T11:21:27.416814Z","end":"2026-09-15T11:21:27.721867Z","steps":["trace[334321934] 'process raft request' (duration: 304.665523ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:21:27.722729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:21:27.416786Z","time spent":"305.300306ms","remote":"127.0.0.1:38190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T11:21:27.974311Z","caller":"traceutil/trace.go:171","msg":"trace[1608599039] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"247.411653ms","start":"2026-09-15T11:21:27.726868Z","end":"2026-09-15T11:21:27.97428Z","steps":["trace[1608599039] 'process raft request' (duration: 247.322552ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:21:27.974346Z","caller":"traceutil/trace.go:171","msg":"trace[1471430376] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"249.582615ms","start":"2026-09-15T11:21:27.724719Z","end":"2026-09-15T11:21:27.974302Z","steps":["trace[1471430376] 'process raft request' (duration: 175.356898ms)","trace[1471430376] 'compare' (duration: 73.893521ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:21:28.697526Z","caller":"traceutil/trace.go:171","msg":"trace[2023030178] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"179.87627ms","start":"2026-09-15T11:21:28.517624Z","end":"2026-09-15T11:21:28.697501Z","steps":["trace[2023030178] 'process raft request' (duration: 179.720636ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:21:28.699384Z","caller":"traceutil/trace.go:171","msg":"trace[1970965368] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"170.219047ms","start":"2026-09-15T11:21:28.529139Z","end":"2026-09-15T11:21:28.699358Z","steps":["trace[1970965368] 'process raft request' (duration: 170.002092ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:22:04.716376Z","caller":"traceutil/trace.go:171","msg":"trace[747864581] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"189.429699ms","start":"2026-09-15T11:22:04.526925Z","end":"2026-09-15T11:22:04.716354Z","steps":["trace[747864581] 'process raft request' (duration: 188.962552ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:22:07.447762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.880739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T11:22:07.448063Z","caller":"traceutil/trace.go:171","msg":"trace[490384488] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"237.629111ms","start":"2026-09-15T11:22:07.210382Z","end":"2026-09-15T11:22:07.448012Z","steps":["trace[490384488] 'process raft request' (duration: 120.060972ms)","trace[490384488] 'compare' (duration: 116.671177ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:22:07.448174Z","caller":"traceutil/trace.go:171","msg":"trace[1859514395] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"222.185046ms","start":"2026-09-15T11:22:07.22595Z","end":"2026-09-15T11:22:07.448135Z","steps":["trace[1859514395] 'read index received' (duration: 104.696878ms)","trace[1859514395] 'applied index is now lower than readState.Index' (duration: 117.483888ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:22:07.448436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.427319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:22:07.448512Z","caller":"traceutil/trace.go:171","msg":"trace[481497201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"222.585942ms","start":"2026-09-15T11:22:07.225912Z","end":"2026-09-15T11:22:07.448498Z","steps":["trace[481497201] 'agreement among raft nodes before linearized reading' (duration: 222.408139ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:22:07.448934Z","caller":"traceutil/trace.go:171","msg":"trace[44853006] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"194.32072ms","start":"2026-09-15T11:22:07.254591Z","end":"2026-09-15T11:22:07.448912Z","steps":["trace[44853006] 'process raft request' (duration: 194.240919ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:22:07.449055Z","caller":"traceutil/trace.go:171","msg":"trace[349569245] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"202.334445ms","start":"2026-09-15T11:22:07.246704Z","end":"2026-09-15T11:22:07.449039Z","steps":["trace[349569245] 'process raft request' (duration: 201.463024ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:22:13.795807Z","caller":"traceutil/trace.go:171","msg":"trace[1416836586] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"237.46897ms","start":"2026-09-15T11:22:13.558269Z","end":"2026-09-15T11:22:13.795738Z","steps":["trace[1416836586] 'read index received' (duration: 237.051976ms)","trace[1416836586] 'applied index is now lower than readState.Index' (duration: 415.734µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:22:13.796316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.999967ms","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-15T11:22:13.796382Z","caller":"traceutil/trace.go:171","msg":"trace[1071539465] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"350.295256ms","start":"2026-09-15T11:22:13.446042Z","end":"2026-09-15T11:22:13.796337Z","steps":["trace[1071539465] 'process raft request' (duration: 349.371525ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:22:13.796475Z","caller":"traceutil/trace.go:171","msg":"trace[596957769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"238.176389ms","start":"2026-09-15T11:22:13.55826Z","end":"2026-09-15T11:22:13.796437Z","steps":["trace[596957769] 'agreement among raft nodes before linearized reading' (duration: 237.827305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:22:13.796573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:22:13.446015Z","time spent":"350.463698ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T11:22:13.916322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.977472ms","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-15T11:22:13.916411Z","caller":"traceutil/trace.go:171","msg":"trace[158662475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"358.082883ms","start":"2026-09-15T11:22:13.558303Z","end":"2026-09-15T11:22:13.916385Z","steps":["trace[158662475] 'agreement among raft nodes before linearized reading' (duration: 237.996247ms)","trace[158662475] 'range keys from in-memory index tree' (duration: 119.906875ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:22:13.916657Z","caller":"traceutil/trace.go:171","msg":"trace[769657920] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"104.734394ms","start":"2026-09-15T11:22:13.811885Z","end":"2026-09-15T11:22:13.916619Z","steps":["trace[769657920] 'read index received' (duration: 15.327152ms)","trace[769657920] 'applied index is now lower than readState.Index' (duration: 89.405852ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:22:13.9169Z","caller":"traceutil/trace.go:171","msg":"trace[1879663358] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"109.98339ms","start":"2026-09-15T11:22:13.806902Z","end":"2026-09-15T11:22:13.916885Z","steps":["trace[1879663358] 'process raft request' (duration: 20.313945ms)","trace[1879663358] 'compare' (duration: 89.012527ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:22:13.917065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:22:13.558147Z","time spent":"358.293967ms","remote":"127.0.0.1:38316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-15T11:22:13.917264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.378651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:22:13.91736Z","caller":"traceutil/trace.go:171","msg":"trace[1350116602] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:613; }","duration":"105.507034ms","start":"2026-09-15T11:22:13.811831Z","end":"2026-09-15T11:22:13.917338Z","steps":["trace[1350116602] 'agreement among raft nodes before linearized reading' (duration: 105.399453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:22:14.456404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.941207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8ktkm\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-09-15T11:22:14.456499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.088256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-15T11:22:14.456546Z","caller":"traceutil/trace.go:171","msg":"trace[896418495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"206.166878ms","start":"2026-09-15T11:22:14.250367Z","end":"2026-09-15T11:22:14.456534Z","steps":["trace[896418495] 'range keys from in-memory index tree' (duration: 205.892114ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:22:14.456545Z","caller":"traceutil/trace.go:171","msg":"trace[1143220798] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8ktkm; range_end:; response_count:1; response_revision:615; }","duration":"423.14406ms","start":"2026-09-15T11:22:14.03337Z","end":"2026-09-15T11:22:14.456514Z","steps":["trace[1143220798] 'range keys from in-memory index tree' (duration: 422.772695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:22:14.456593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:22:14.033347Z","time spent":"423.23272ms","remote":"127.0.0.1:38242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-8ktkm\" "} {"level":"info","ts":"2026-09-15T11:22:14.49119Z","caller":"traceutil/trace.go:171","msg":"trace[1370663397] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"168.731942ms","start":"2026-09-15T11:22:14.322431Z","end":"2026-09-15T11:22:14.491163Z","steps":["trace[1370663397] 'process raft request' (duration: 168.198146ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:22:30.831669Z","caller":"traceutil/trace.go:171","msg":"trace[1251483348] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"228.805895ms","start":"2026-09-15T11:22:30.602825Z","end":"2026-09-15T11:22:30.831631Z","steps":["trace[1251483348] 'process raft request' (duration: 228.432551ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:23:19.012905Z","caller":"traceutil/trace.go:171","msg":"trace[1533579922] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"108.405186ms","start":"2026-09-15T11:23:18.904471Z","end":"2026-09-15T11:23:19.012876Z","steps":["trace[1533579922] 'process raft request' (duration: 108.217854ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:23:19.02131Z","caller":"traceutil/trace.go:171","msg":"trace[447272718] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"113.167537ms","start":"2026-09-15T11:23:18.908102Z","end":"2026-09-15T11:23:19.021269Z","steps":["trace[447272718] 'process raft request' (duration: 112.992594ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:23:19.38134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.910351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-15T11:23:19.381446Z","caller":"traceutil/trace.go:171","msg":"trace[2091952999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1331; }","duration":"292.060353ms","start":"2026-09-15T11:23:19.089359Z","end":"2026-09-15T11:23:19.38142Z","steps":["trace[2091952999] 'range keys from in-memory index tree' (duration: 291.69156ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:23:19.768923Z","caller":"traceutil/trace.go:171","msg":"trace[127318924] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"379.338405ms","start":"2026-09-15T11:23:19.389555Z","end":"2026-09-15T11:23:19.768894Z","steps":["trace[127318924] 'process raft request' (duration: 379.036692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:23:19.769079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:23:19.389541Z","time spent":"379.452736ms","remote":"127.0.0.1:38242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4334,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T11:23:19.769112Z","caller":"traceutil/trace.go:171","msg":"trace[903108232] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"358.952082ms","start":"2026-09-15T11:23:19.410131Z","end":"2026-09-15T11:23:19.769083Z","steps":["trace[903108232] 'process raft request' (duration: 358.625069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:23:19.769339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:23:19.410117Z","time spent":"359.048704ms","remote":"127.0.0.1:38316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T11:23:43.028458Z","caller":"traceutil/trace.go:171","msg":"trace[1698984299] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"118.163305ms","start":"2026-09-15T11:23:42.910271Z","end":"2026-09-15T11:23:43.028434Z","steps":["trace[1698984299] 'process raft request' (duration: 117.979004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:23:54.471904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.843721ms","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-15T11:23:54.472011Z","caller":"traceutil/trace.go:171","msg":"trace[367377590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1870; }","duration":"114.014982ms","start":"2026-09-15T11:23:54.357973Z","end":"2026-09-15T11:23:54.471988Z","steps":["trace[367377590] 'range keys from in-memory index tree' (duration: 113.624519ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:23:54.643703Z","caller":"traceutil/trace.go:171","msg":"trace[2125726060] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"166.299858ms","start":"2026-09-15T11:23:54.477377Z","end":"2026-09-15T11:23:54.643677Z","steps":["trace[2125726060] 'read index received' (duration: 166.046846ms)","trace[2125726060] 'applied index is now lower than readState.Index' (duration: 252.062µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:23:54.644043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.691021ms","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-15T11:23:54.644123Z","caller":"traceutil/trace.go:171","msg":"trace[1268430605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1871; }","duration":"166.826702ms","start":"2026-09-15T11:23:54.477282Z","end":"2026-09-15T11:23:54.644109Z","steps":["trace[1268430605] 'agreement among raft nodes before linearized reading' (duration: 166.55164ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:23:54.643785Z","caller":"traceutil/trace.go:171","msg":"trace[585373791] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"166.46224ms","start":"2026-09-15T11:23:54.477288Z","end":"2026-09-15T11:23:54.643751Z","steps":["trace[585373791] 'process raft request' (duration: 166.223157ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:23:54.644658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.026386ms","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-15T11:23:54.645326Z","caller":"traceutil/trace.go:171","msg":"trace[835393486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1871; }","duration":"141.800403ms","start":"2026-09-15T11:23:54.503496Z","end":"2026-09-15T11:23:54.645297Z","steps":["trace[835393486] 'agreement among raft nodes before linearized reading' (duration: 140.981646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:23:54.645063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.433507ms","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-15T11:23:54.645637Z","caller":"traceutil/trace.go:171","msg":"trace[1417256709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1871; }","duration":"134.980702ms","start":"2026-09-15T11:23:54.510577Z","end":"2026-09-15T11:23:54.645557Z","steps":["trace[1417256709] 'agreement among raft nodes before linearized reading' (duration: 134.267086ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:23:54.763779Z","caller":"traceutil/trace.go:171","msg":"trace[807018764] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"112.820854ms","start":"2026-09-15T11:23:54.650926Z","end":"2026-09-15T11:23:54.763747Z","steps":["trace[807018764] 'process raft request' (duration: 53.498726ms)","trace[807018764] 'compare' (duration: 59.167487ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:23:54.856762Z","caller":"traceutil/trace.go:171","msg":"trace[1781534960] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"205.078192ms","start":"2026-09-15T11:23:54.651647Z","end":"2026-09-15T11:23:54.856726Z","steps":["trace[1781534960] 'process raft request' (duration: 204.86807ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:23:54.857053Z","caller":"traceutil/trace.go:171","msg":"trace[29289726] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"205.353404ms","start":"2026-09-15T11:23:54.65168Z","end":"2026-09-15T11:23:54.857034Z","steps":["trace[29289726] 'process raft request' (duration: 204.990871ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:23:54.857406Z","caller":"traceutil/trace.go:171","msg":"trace[651440758] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1945; }","duration":"173.357661ms","start":"2026-09-15T11:23:54.684029Z","end":"2026-09-15T11:23:54.857387Z","steps":["trace[651440758] 'read index received' (duration: 20.488115ms)","trace[651440758] 'applied index is now lower than readState.Index' (duration: 152.867566ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:23:54.85747Z","caller":"traceutil/trace.go:171","msg":"trace[827983392] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"205.677206ms","start":"2026-09-15T11:23:54.651767Z","end":"2026-09-15T11:23:54.857445Z","steps":["trace[827983392] 'process raft request' (duration: 205.181812ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:23:54.857543Z","caller":"traceutil/trace.go:171","msg":"trace[1334231843] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"204.450607ms","start":"2026-09-15T11:23:54.653071Z","end":"2026-09-15T11:23:54.857522Z","steps":["trace[1334231843] 'process raft request' (duration: 204.225494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:23:54.857741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.859528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-15T11:23:54.857771Z","caller":"traceutil/trace.go:171","msg":"trace[1008416527] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1876; }","duration":"143.942188ms","start":"2026-09-15T11:23:54.713819Z","end":"2026-09-15T11:23:54.857761Z","steps":["trace[1008416527] 'agreement among raft nodes before linearized reading' (duration: 143.799907ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:23:54.857883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.826275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-09-15T11:23:54.857902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.306224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-988623f9-f2b7-4d8c-83eb-e9e81d5ccf79\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-09-15T11:23:54.857942Z","caller":"traceutil/trace.go:171","msg":"trace[422342245] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1876; }","duration":"173.951145ms","start":"2026-09-15T11:23:54.683969Z","end":"2026-09-15T11:23:54.857921Z","steps":["trace[422342245] 'agreement among raft nodes before linearized reading' (duration: 173.733704ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:23:54.857997Z","caller":"traceutil/trace.go:171","msg":"trace[1060679480] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-988623f9-f2b7-4d8c-83eb-e9e81d5ccf79; range_end:; response_count:1; response_revision:1876; }","duration":"131.447675ms","start":"2026-09-15T11:23:54.726522Z","end":"2026-09-15T11:23:54.85797Z","steps":["trace[1060679480] 'agreement among raft nodes before linearized reading' (duration: 131.254463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:23:55.011002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.706213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T11:23:55.01116Z","caller":"traceutil/trace.go:171","msg":"trace[1993564972] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"145.968374ms","start":"2026-09-15T11:23:54.865167Z","end":"2026-09-15T11:23:55.011136Z","steps":["trace[1993564972] 'process raft request' (duration: 16.681027ms)","trace[1993564972] 'compare' (duration: 128.542921ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:24:29.003295Z","caller":"traceutil/trace.go:171","msg":"trace[1554260373] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"112.872944ms","start":"2026-09-15T11:24:28.890386Z","end":"2026-09-15T11:24:29.003259Z","steps":["trace[1554260373] 'process raft request' (duration: 112.649652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:24:29.479319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.819231ms","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":"warn","ts":"2026-09-15T11:24:29.479367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.595787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:24:29.479428Z","caller":"traceutil/trace.go:171","msg":"trace[1753274391] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2117; }","duration":"294.978111ms","start":"2026-09-15T11:24:29.184421Z","end":"2026-09-15T11:24:29.479399Z","steps":["trace[1753274391] 'range keys from in-memory index tree' (duration: 294.59257ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:24:29.479513Z","caller":"traceutil/trace.go:171","msg":"trace[883487892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2117; }","duration":"251.778419ms","start":"2026-09-15T11:24:29.227674Z","end":"2026-09-15T11:24:29.479452Z","steps":["trace[883487892] 'range keys from in-memory index tree' (duration: 251.417955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:24:29.480017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.098206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T11:24:29.480388Z","caller":"traceutil/trace.go:171","msg":"trace[1305006231] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"241.261454ms","start":"2026-09-15T11:24:29.239102Z","end":"2026-09-15T11:24:29.480363Z","steps":["trace[1305006231] 'process raft request' (duration: 74.206461ms)","trace[1305006231] 'compare' (duration: 165.791185ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:24:29.480706Z","caller":"traceutil/trace.go:171","msg":"trace[1530969935] linearizableReadLoop","detail":"{readStateIndex:2199; appliedIndex:2198; }","duration":"183.878412ms","start":"2026-09-15T11:24:29.296807Z","end":"2026-09-15T11:24:29.480686Z","steps":["trace[1530969935] 'read index received' (duration: 16.495087ms)","trace[1530969935] 'applied index is now lower than readState.Index' (duration: 167.380535ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:24:29.480959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.145775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-15T11:24:29.48097Z","caller":"traceutil/trace.go:171","msg":"trace[1522705691] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"180.863711ms","start":"2026-09-15T11:24:29.300076Z","end":"2026-09-15T11:24:29.480939Z","steps":["trace[1522705691] 'process raft request' (duration: 180.630528ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:24:29.480998Z","caller":"traceutil/trace.go:171","msg":"trace[933069601] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2118; }","duration":"184.273715ms","start":"2026-09-15T11:24:29.296711Z","end":"2026-09-15T11:24:29.480985Z","steps":["trace[933069601] 'agreement among raft nodes before linearized reading' (duration: 184.085733ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:24:29.495772Z","caller":"traceutil/trace.go:171","msg":"trace[998542672] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"104.750516ms","start":"2026-09-15T11:24:29.391001Z","end":"2026-09-15T11:24:29.495751Z","steps":["trace[998542672] 'process raft request' (duration: 104.203003ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:24:29.802009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.676725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T11:24:29.802129Z","caller":"traceutil/trace.go:171","msg":"trace[570833060] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"196.36445ms","start":"2026-09-15T11:24:29.605742Z","end":"2026-09-15T11:24:29.802107Z","steps":["trace[570833060] 'read index received' (duration: 14.358812ms)","trace[570833060] 'applied index is now lower than readState.Index' (duration: 182.003858ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:24:29.80237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.622661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-15T11:24:29.802424Z","caller":"traceutil/trace.go:171","msg":"trace[960221781] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2121; }","duration":"196.726222ms","start":"2026-09-15T11:24:29.605684Z","end":"2026-09-15T11:24:29.802411Z","steps":["trace[960221781] 'agreement among raft nodes before linearized reading' (duration: 196.556691ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:24:29.802363Z","caller":"traceutil/trace.go:171","msg":"trace[1342475910] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"315.965579ms","start":"2026-09-15T11:24:29.486226Z","end":"2026-09-15T11:24:29.802192Z","steps":["trace[1342475910] 'process raft request' (duration: 133.956062ms)","trace[1342475910] 'compare' (duration: 181.552164ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:24:29.802602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:24:29.486202Z","time spent":"316.312101ms","remote":"127.0.0.1:38316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T11:30:45.766569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.659916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b9cee152-9142-4fab-b753-8f45a2b5a6c6\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-09-15T11:30:45.766661Z","caller":"traceutil/trace.go:171","msg":"trace[606330415] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b9cee152-9142-4fab-b753-8f45a2b5a6c6; range_end:; response_count:1; response_revision:4825; }","duration":"118.811857ms","start":"2026-09-15T11:30:45.647828Z","end":"2026-09-15T11:30:45.76664Z","steps":["trace[606330415] 'range keys from in-memory index tree' (duration: 118.462316ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:30:45.796533Z","caller":"traceutil/trace.go:171","msg":"trace[802928741] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"132.12079ms","start":"2026-09-15T11:30:45.664371Z","end":"2026-09-15T11:30:45.796492Z","steps":["trace[802928741] 'process raft request' (duration: 131.713728ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:30:50.733409Z","caller":"traceutil/trace.go:171","msg":"trace[220897007] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"105.960657ms","start":"2026-09-15T11:30:50.627431Z","end":"2026-09-15T11:30:50.733392Z","steps":["trace[220897007] 'process raft request' (duration: 105.856577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:30:54.65559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.931844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6cac7893-7844-459b-817f-d08f9b935d43\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-15T11:30:54.65569Z","caller":"traceutil/trace.go:171","msg":"trace[594379905] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6cac7893-7844-459b-817f-d08f9b935d43; range_end:; response_count:1; response_revision:5016; }","duration":"209.098535ms","start":"2026-09-15T11:30:54.446568Z","end":"2026-09-15T11:30:54.655666Z","steps":["trace[594379905] 'range keys from in-memory index tree' (duration: 208.712724ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:31:08.031441Z","caller":"traceutil/trace.go:171","msg":"trace[973360028] transaction","detail":"{read_only:false; response_revision:5174; number_of_response:1; }","duration":"116.488523ms","start":"2026-09-15T11:31:07.914931Z","end":"2026-09-15T11:31:08.031419Z","steps":["trace[973360028] 'process raft request' (duration: 116.338622ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:31:08.096599Z","caller":"traceutil/trace.go:171","msg":"trace[1124347478] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"144.323797ms","start":"2026-09-15T11:31:07.952241Z","end":"2026-09-15T11:31:08.096564Z","steps":["trace[1124347478] 'process raft request' (duration: 144.050626ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:31:08.097255Z","caller":"traceutil/trace.go:171","msg":"trace[958421134] linearizableReadLoop","detail":"{readStateIndex:5374; appliedIndex:5372; }","duration":"134.607416ms","start":"2026-09-15T11:31:07.962622Z","end":"2026-09-15T11:31:08.09723Z","steps":["trace[958421134] 'read index received' (duration: 68.606271ms)","trace[958421134] 'applied index is now lower than readState.Index' (duration: 65.999985ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T11:31:08.097474Z","caller":"traceutil/trace.go:171","msg":"trace[1989426794] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"122.267269ms","start":"2026-09-15T11:31:07.975197Z","end":"2026-09-15T11:31:08.097465Z","steps":["trace[1989426794] 'process raft request' (duration: 121.312753ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:31:08.098159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.505831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-15T11:31:08.098227Z","caller":"traceutil/trace.go:171","msg":"trace[521274442] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5176; }","duration":"135.616773ms","start":"2026-09-15T11:31:07.96259Z","end":"2026-09-15T11:31:08.098207Z","steps":["trace[521274442] 'agreement among raft nodes before linearized reading' (duration: 135.372782ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:31:08.479668Z","caller":"traceutil/trace.go:171","msg":"trace[1588760486] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"439.290303ms","start":"2026-09-15T11:31:08.040358Z","end":"2026-09-15T11:31:08.479649Z","steps":["trace[1588760486] 'process raft request' (duration: 438.93115ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:31:08.479772Z","caller":"traceutil/trace.go:171","msg":"trace[109443618] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"439.956088ms","start":"2026-09-15T11:31:08.039783Z","end":"2026-09-15T11:31:08.47974Z","steps":["trace[109443618] 'process raft request' (duration: 439.454544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:31:08.479949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:31:08.039774Z","time spent":"440.066388ms","remote":"127.0.0.1:38236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T11:31:08.480362Z","caller":"traceutil/trace.go:171","msg":"trace[1956553259] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"131.330516ms","start":"2026-09-15T11:31:08.34897Z","end":"2026-09-15T11:31:08.4803Z","steps":["trace[1956553259] 'process raft request' (duration: 130.48686ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T11:31:08.480588Z","caller":"traceutil/trace.go:171","msg":"trace[1150336613] linearizableReadLoop","detail":"{readStateIndex:5379; appliedIndex:5375; }","duration":"383.253631ms","start":"2026-09-15T11:31:08.09732Z","end":"2026-09-15T11:31:08.480574Z","steps":["trace[1150336613] 'read index received' (duration: 363.986039ms)","trace[1150336613] 'applied index is now lower than readState.Index' (duration: 19.265692ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:31:08.480728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.765871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-15T11:31:08.480753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:31:08.040352Z","time spent":"439.407314ms","remote":"127.0.0.1:38328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1504,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T11:31:08.480784Z","caller":"traceutil/trace.go:171","msg":"trace[881025245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5182; }","duration":"408.867381ms","start":"2026-09-15T11:31:08.071904Z","end":"2026-09-15T11:31:08.480771Z","steps":["trace[881025245] 'agreement among raft nodes before linearized reading' (duration: 408.69181ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:31:08.48082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:31:08.071889Z","time spent":"408.923051ms","remote":"127.0.0.1:38316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-15T11:31:08.479682Z","caller":"traceutil/trace.go:171","msg":"trace[451829828] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"439.919767ms","start":"2026-09-15T11:31:08.03971Z","end":"2026-09-15T11:31:08.47963Z","steps":["trace[451829828] 'process raft request' (duration: 421.580571ms)","trace[451829828] 'compare' (duration: 17.256648ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T11:31:08.480989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.982023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6cac7893-7844-459b-817f-d08f9b935d43\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-15T11:31:08.481119Z","caller":"traceutil/trace.go:171","msg":"trace[1293217498] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6cac7893-7844-459b-817f-d08f9b935d43; range_end:; response_count:1; response_revision:5182; }","duration":"379.095634ms","start":"2026-09-15T11:31:08.101987Z","end":"2026-09-15T11:31:08.481083Z","steps":["trace[1293217498] 'agreement among raft nodes before linearized reading' (duration: 378.766632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:31:08.481202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:31:08.101907Z","time spent":"379.255585ms","remote":"127.0.0.1:38222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1346,"request content":"key:\"/registry/persistentvolumes/pvc-6cac7893-7844-459b-817f-d08f9b935d43\" "} {"level":"warn","ts":"2026-09-15T11:31:08.481209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.906683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-15T11:31:08.481294Z","caller":"traceutil/trace.go:171","msg":"trace[928585901] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5182; }","duration":"244.021325ms","start":"2026-09-15T11:31:08.237248Z","end":"2026-09-15T11:31:08.48127Z","steps":["trace[928585901] 'agreement among raft nodes before linearized reading' (duration: 243.842914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:31:08.481006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:31:08.039686Z","time spent":"441.265756ms","remote":"127.0.0.1:38328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1427,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T11:31:08.481446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.266302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T11:31:08.481599Z","caller":"traceutil/trace.go:171","msg":"trace[1273324095] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5182; }","duration":"443.149417ms","start":"2026-09-15T11:31:08.038434Z","end":"2026-09-15T11:31:08.481583Z","steps":["trace[1273324095] 'agreement among raft nodes before linearized reading' (duration: 442.168201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:31:08.481645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:31:08.038404Z","time spent":"443.227398ms","remote":"127.0.0.1:38432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-09-15T11:31:08.479577Z","caller":"traceutil/trace.go:171","msg":"trace[354137197] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"439.820676ms","start":"2026-09-15T11:31:08.039718Z","end":"2026-09-15T11:31:08.479538Z","steps":["trace[354137197] 'process raft request' (duration: 439.376163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:31:08.483794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:31:08.039696Z","time spent":"444.022283ms","remote":"127.0.0.1:38236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":949,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T11:31:08.484437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.032317ms","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-09-15T11:31:08.484501Z","caller":"traceutil/trace.go:171","msg":"trace[803377729] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5182; }","duration":"459.123518ms","start":"2026-09-15T11:31:08.025358Z","end":"2026-09-15T11:31:08.484482Z","steps":["trace[803377729] 'agreement among raft nodes before linearized reading' (duration: 455.342694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T11:31:08.48454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T11:31:08.025343Z","time spent":"459.187918ms","remote":"127.0.0.1:46046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-09-15T11:31:23.731435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2839} {"level":"info","ts":"2026-09-15T11:31:23.820625Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2839,"took":"87.537561ms","hash":335964369,"current-db-size-bytes":52137984,"current-db-size":"52 MB","current-db-size-in-use-bytes":27525120,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-15T11:31:23.820716Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":335964369,"revision":2839,"compact-revision":-1} {"level":"info","ts":"2026-09-15T11:36:23.737881Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5308} {"level":"info","ts":"2026-09-15T11:36:23.821864Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5308,"took":"82.187212ms","hash":1576209603,"current-db-size-bytes":52137984,"current-db-size":"52 MB","current-db-size-in-use-bytes":18821120,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-15T11:36:23.822364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1576209603,"revision":5308,"compact-revision":2839}